builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-079 starttime: 1487654873.5 results: success (0) revision: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.500429) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.500958) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.501307) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.518759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.519161) ========= 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=1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 21:27:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2017-02-20 21:27:54 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097698 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.631006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.631355) ========= 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=1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015960 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.681046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:53.681420) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 --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=1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 21:27:54,762 truncating revision to first 12 chars 2017-02-20 21:27:54,762 Setting DEBUG logging. 2017-02-20 21:27:54,762 attempt 1/10 2017-02-20 21:27:54,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/276bd9ed3dbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 21:27:55,075 unpacking tar archive at: mozilla-central-276bd9ed3dbf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.674322 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:54.369218) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:54.369573) ========= script_repo_revision: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:54.370016) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:54.370306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 21:27:54.379543) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-20 21:27:54.379892) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:27:55 INFO - MultiFileLogger online at 20170220 21:27:55 in /builds/slave/test 21:27:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:27:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:27:55 INFO - {'append_to_log': False, 21:27:55 INFO - 'base_work_dir': '/builds/slave/test', 21:27:55 INFO - 'blob_upload_branch': 'Firefox', 21:27:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:27:55 INFO - 'branch': 'Firefox', 21:27:55 INFO - 'buildbot_json_path': 'buildprops.json', 21:27:55 INFO - 'config_files': ('talos/linux_config.py',), 21:27:55 INFO - 'default_actions': ('clobber', 21:27:55 INFO - 'read-buildbot-config', 21:27:55 INFO - 'download-and-extract', 21:27:55 INFO - 'populate-webroot', 21:27:55 INFO - 'create-virtualenv', 21:27:55 INFO - 'install', 21:27:55 INFO - 'run-tests'), 21:27:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:27:55 INFO - 'download_minidump_stackwalk': True, 21:27:55 INFO - 'download_symbols': 'ondemand', 21:27:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:27:55 INFO - 'tooltool.py': '/tools/tooltool.py', 21:27:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:27:55 INFO - '/tools/misc-python/virtualenv.py')}, 21:27:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:27:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:27:55 INFO - 'gecko_profile': False, 21:27:55 INFO - 'gecko_profile_interval': 0, 21:27:55 INFO - 'installer_path': 'installer.exe', 21:27:55 INFO - 'log_level': 'info', 21:27:55 INFO - 'log_name': 'talos', 21:27:55 INFO - 'log_to_console': True, 21:27:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:27:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:27:55 INFO - 'opt_config_files': (), 21:27:55 INFO - 'pip_index': False, 21:27:55 INFO - 'suite': 'dromaeojs', 21:27:55 INFO - 'system_bits': '32', 21:27:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:27:55 INFO - 'title': 'talos-linux64-ix-079', 21:27:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:27:55 INFO - 'use_talos_json': True, 21:27:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:27:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:27:55 INFO - 'work_dir': 'build'} 21:27:55 INFO - [mozharness: 2017-02-21 05:27:55.552235Z] Running clobber step. 21:27:55 INFO - Running pre-action listener: _resource_record_pre_action 21:27:55 INFO - Running main action method: clobber 21:27:55 INFO - rmtree: /builds/slave/test/build 21:27:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:27:56 INFO - Running post-action listener: _resource_record_post_action 21:27:56 INFO - [mozharness: 2017-02-21 05:27:56.470164Z] Finished clobber step (success) 21:27:56 INFO - [mozharness: 2017-02-21 05:27:56.470266Z] Running read-buildbot-config step. 21:27:56 INFO - Running pre-action listener: _resource_record_pre_action 21:27:56 INFO - Running main action method: read_buildbot_config 21:27:56 INFO - Using buildbot properties: 21:27:56 INFO - { 21:27:56 INFO - "project": "", 21:27:56 INFO - "product": "firefox", 21:27:56 INFO - "who": "philringnalda@gmail.com", 21:27:56 INFO - "installer_path": "public/build/target.tar.bz2", 21:27:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:27:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 21:27:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:27:56 INFO - "stage_platform": "linux64-pgo", 21:27:56 INFO - "basedir": "/builds/slave/test", 21:27:56 INFO - "buildnumber": 39, 21:27:56 INFO - "platform": "ubuntu64_hw", 21:27:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:27:56 INFO - "slavebuilddir": "test", 21:27:56 INFO - "taskId": "eVDTAEWDSK6Q0ovt1lhJuQ", 21:27:56 INFO - "branch": "mozilla-central", 21:27:56 INFO - "script_repo_revision": "production", 21:27:56 INFO - "revision": "276bd9ed3dbf85f4a6dac67df54a5f2631804de5", 21:27:56 INFO - "slavename": "talos-linux64-ix-079", 21:27:56 INFO - "repo_path": "mozilla-central" 21:27:56 INFO - } 21:27:56 INFO - Finding installer, test and symbols from parent task. 21:27:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eVDTAEWDSK6Q0ovt1lhJuQ'}, attempt #1 21:27:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eVDTAEWDSK6Q0ovt1lhJuQ'}, attempt #1 21:27:57 INFO - Task dependencies: fYgpTw-3SNGzJzueFjNOtg 21:27:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg'}, attempt #1 21:27:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2 21:27:57 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/test_packages.json 21:27:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.crashreporter-symbols.zip 21:27:57 INFO - Running post-action listener: _resource_record_post_action 21:27:57 INFO - [mozharness: 2017-02-21 05:27:57.852588Z] Finished read-buildbot-config step (success) 21:27:57 INFO - [mozharness: 2017-02-21 05:27:57.852850Z] Running download-and-extract step. 21:27:57 INFO - Running pre-action listener: _resource_record_pre_action 21:27:57 INFO - Running main action method: download_and_extract 21:27:57 INFO - mkdir: /builds/slave/test/build/tests 21:27: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')]} 21:27:57 INFO - trying https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.test_packages.json 21:27:57 INFO - Downloading https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:27:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:27:58 INFO - Downloaded 896 bytes. 21:27:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:27:58 INFO - Using the following test package requirements: 21:27:58 INFO - {u'common': [u'target.common.tests.zip'], 21:27:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:27:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:27:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:27:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:27:58 INFO - u'mozbase': [u'target.common.tests.zip'], 21:27:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:27:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:27:58 INFO - u'web-platform': [u'target.common.tests.zip', 21:27:58 INFO - u'target.web-platform.tests.zip'], 21:27:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:27:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 21:27:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.common.tests.zip 21:27:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:27:58 INFO - Fetch https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.common.tests.zip into memory 21:27:59 INFO - Expected file size: 59241116 21:27:59 INFO - Obtained file size: 59241116 21:28:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 21:28:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.common.tests.zip 21:28:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:28:09 INFO - Fetch https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.common.tests.zip into memory 21:28:10 INFO - Expected file size: 59241116 21:28:10 INFO - Obtained file size: 59241116 21:28:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.talos.tests.zip 21:28:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:28:21 INFO - Fetch https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.talos.tests.zip into memory 21:28:21 INFO - Expected file size: 14404319 21:28:21 INFO - Obtained file size: 14404319 21:28: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')]} 21:28:22 INFO - trying https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2 21:28:22 INFO - Downloading https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:28:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:28:24 INFO - Downloaded 66807855 bytes. 21:28:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2 21:28:24 INFO - mkdir: /builds/slave/test/properties 21:28:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:28:24 INFO - Writing to file /builds/slave/test/properties/build_url 21:28:24 INFO - Contents: 21:28:24 INFO - build_url:https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2 21:28:24 INFO - Running post-action listener: _resource_record_post_action 21:28:24 INFO - Running post-action listener: set_extra_try_arguments 21:28:24 INFO - [mozharness: 2017-02-21 05:28:24.257294Z] Finished download-and-extract step (success) 21:28:24 INFO - [mozharness: 2017-02-21 05:28:24.257500Z] Running populate-webroot step. 21:28:24 INFO - Running pre-action listener: _resource_record_pre_action 21:28:24 INFO - Running main action method: populate_webroot 21:28:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:28:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:28:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:28:24 INFO - '--tppagecycles', 21:28:24 INFO - '7'], 21:28:24 INFO - 'tests': ['tsvgm']}}, 21:28:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:28:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:28:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:28:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:28:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:28:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:28:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['damp', 'tps']}, 21:28:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'tests': ['damp', 'tps']}, 21:28:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['dromaeo_dom']}, 21:28:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:28:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['basic_compositor_video']}, 21:28:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:28:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['a11yr', 21:28:24 INFO - 'ts_paint', 21:28:24 INFO - 'tpaint', 21:28:24 INFO - 'sessionrestore', 21:28:24 INFO - 'sessionrestore_no_auto_restore', 21:28:24 INFO - 'tabpaint']}, 21:28:24 INFO - 'other-e10s': {'tests': ['a11yr', 21:28:24 INFO - 'ts_paint', 21:28:24 INFO - 'tpaint', 21:28:24 INFO - 'sessionrestore', 21:28:24 INFO - 'sessionrestore_no_auto_restore', 21:28:24 INFO - 'tabpaint']}, 21:28:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:28:24 INFO - 'ts_paint', 21:28:24 INFO - 'tpaint', 21:28:24 INFO - 'sessionrestore', 21:28:24 INFO - 'sessionrestore_no_auto_restore', 21:28:24 INFO - 'tabpaint']}, 21:28:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:28:24 INFO - 'ts_paint', 21:28:24 INFO - 'tpaint', 21:28:24 INFO - 'sessionrestore', 21:28:24 INFO - 'sessionrestore_no_auto_restore', 21:28:24 INFO - 'tabpaint']}, 21:28:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['a11yr', 21:28:24 INFO - 'ts_paint', 21:28:24 INFO - 'tpaint', 21:28:24 INFO - 'sessionrestore', 21:28:24 INFO - 'sessionrestore_no_auto_restore', 21:28:24 INFO - 'tabpaint']}, 21:28:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['a11yr', 21:28:24 INFO - 'ts_paint', 21:28:24 INFO - 'tpaint', 21:28:24 INFO - 'sessionrestore', 21:28:24 INFO - 'sessionrestore_no_auto_restore', 21:28:24 INFO - 'tabpaint']}, 21:28:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['tsvgx', 21:28:24 INFO - 'tsvgr_opacity', 21:28:24 INFO - 'tart', 21:28:24 INFO - 'tscrollx', 21:28:24 INFO - 'cart', 21:28:24 INFO - 'tsvg_static']}, 21:28:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:28:24 INFO - 'tsvgr_opacity', 21:28:24 INFO - 'tart', 21:28:24 INFO - 'tscrollx', 21:28:24 INFO - 'cart', 21:28:24 INFO - 'tsvg_static']}, 21:28:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'talos_options': ['--disable-e10s'], 21:28:24 INFO - 'tests': ['tp5o']}, 21:28:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:28:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'talos_options': ['--disable-e10s', 21:28:24 INFO - '--xperf_path', 21:28:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:28:24 INFO - 'tests': ['tp5n']}, 21:28:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:28:24 INFO - 'talos_options': ['--xperf_path', 21:28:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:28:24 INFO - 'tests': ['tp5n']}}, 21:28:24 INFO - 'talos.zip': {'path': '', 21:28:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:28:24 INFO - Running post-action listener: _resource_record_post_action 21:28:24 INFO - [mozharness: 2017-02-21 05:28:24.274885Z] Finished populate-webroot step (success) 21:28:24 INFO - [mozharness: 2017-02-21 05:28:24.274978Z] Running create-virtualenv step. 21:28:24 INFO - Running pre-action listener: _resource_record_pre_action 21:28:24 INFO - Running main action method: create_virtualenv 21:28:24 INFO - Creating virtualenv /builds/slave/test/build/venv 21:28:24 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 21:28:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:28:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:28:24 INFO - Using real prefix '/usr' 21:28:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:28:24 INFO - Installing distribute.............................................................................................................................................................................................done. 21:28:26 INFO - Installing pip.................done. 21:28:26 INFO - Return code: 0 21:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:28:27 INFO - Reading from file tmpfile_stdout 21:28:27 INFO - Output received: 21:28:27 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) 21:28:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:28:27 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')]} 21:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:27 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')]} 21:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:27 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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:27 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 21:28:27 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 21:28:27 INFO - Using env: {'DISPLAY': ':0', 21:28:27 INFO - 'HOME': '/home/cltbld', 21:28:27 INFO - 'LANG': 'en_US.UTF-8', 21:28:27 INFO - 'LANGUAGE': 'en_US:en', 21:28:27 INFO - 'LOGNAME': 'cltbld', 21:28:27 INFO - 'MAIL': '/var/mail/cltbld', 21:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:27 INFO - 'MOZ_NO_REMOTE': '1', 21:28:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:27 INFO - 'NO_EM_RESTART': '1', 21:28:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:27 INFO - 'PWD': '/builds/slave/test', 21:28:27 INFO - 'SHELL': '/bin/bash', 21:28:27 INFO - 'SHLVL': '1', 21:28:27 INFO - 'TERM': 'linux', 21:28:27 INFO - 'TMOUT': '86400', 21:28:27 INFO - 'USER': 'cltbld', 21:28:27 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:27 INFO - '_': '/tools/buildbot/bin/python'} 21:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:27 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 21:28:27 INFO - Cleaning up... 21:28:27 INFO - Return code: 0 21:28:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:28:27 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')]} 21:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:27 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')]} 21:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:27 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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:27 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 21:28:27 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 21:28:27 INFO - Using env: {'DISPLAY': ':0', 21:28:27 INFO - 'HOME': '/home/cltbld', 21:28:27 INFO - 'LANG': 'en_US.UTF-8', 21:28:27 INFO - 'LANGUAGE': 'en_US:en', 21:28:27 INFO - 'LOGNAME': 'cltbld', 21:28:27 INFO - 'MAIL': '/var/mail/cltbld', 21:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:27 INFO - 'MOZ_NO_REMOTE': '1', 21:28:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:27 INFO - 'NO_EM_RESTART': '1', 21:28:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:27 INFO - 'PWD': '/builds/slave/test', 21:28:27 INFO - 'SHELL': '/bin/bash', 21:28:27 INFO - 'SHLVL': '1', 21:28:27 INFO - 'TERM': 'linux', 21:28:27 INFO - 'TMOUT': '86400', 21:28:27 INFO - 'USER': 'cltbld', 21:28:27 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:27 INFO - '_': '/tools/buildbot/bin/python'} 21:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:27 INFO - Downloading/unpacking psutil>=3.1.1 21:28:27 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 21:28:27 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 21:28:27 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 21:28:27 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 21:28:27 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 21:28:27 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 21:28:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:28:29 INFO - Installing collected packages: psutil 21:28:29 INFO - Running setup.py install for psutil 21:28:29 INFO - building 'psutil._psutil_linux' extension 21:28:29 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 21:28:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:28:29 INFO - building 'psutil._psutil_posix' extension 21:28:29 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 21:28:30 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 21:28:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:28:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:28:30 INFO - Successfully installed psutil 21:28:30 INFO - Cleaning up... 21:28:30 INFO - Return code: 0 21:28:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:28:30 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')]} 21:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:30 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')]} 21:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:30 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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:30 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 21:28:30 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 21:28:30 INFO - Using env: {'DISPLAY': ':0', 21:28:30 INFO - 'HOME': '/home/cltbld', 21:28:30 INFO - 'LANG': 'en_US.UTF-8', 21:28:30 INFO - 'LANGUAGE': 'en_US:en', 21:28:30 INFO - 'LOGNAME': 'cltbld', 21:28:30 INFO - 'MAIL': '/var/mail/cltbld', 21:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:30 INFO - 'MOZ_NO_REMOTE': '1', 21:28:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:30 INFO - 'NO_EM_RESTART': '1', 21:28:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:30 INFO - 'PWD': '/builds/slave/test', 21:28:30 INFO - 'SHELL': '/bin/bash', 21:28:30 INFO - 'SHLVL': '1', 21:28:30 INFO - 'TERM': 'linux', 21:28:30 INFO - 'TMOUT': '86400', 21:28:30 INFO - 'USER': 'cltbld', 21:28:30 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:30 INFO - '_': '/tools/buildbot/bin/python'} 21:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:28:30 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 21:28:30 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 21:28:30 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 21:28:30 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 21:28:30 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 21:28:30 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 21:28:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:28:32 INFO - Installing collected packages: mozsystemmonitor 21:28:32 INFO - Running setup.py install for mozsystemmonitor 21:28:32 INFO - Successfully installed mozsystemmonitor 21:28:32 INFO - Cleaning up... 21:28:32 INFO - Return code: 0 21:28:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:32 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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:32 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 21:28:32 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 21:28:32 INFO - Using env: {'DISPLAY': ':0', 21:28:32 INFO - 'HOME': '/home/cltbld', 21:28:32 INFO - 'LANG': 'en_US.UTF-8', 21:28:32 INFO - 'LANGUAGE': 'en_US:en', 21:28:32 INFO - 'LOGNAME': 'cltbld', 21:28:32 INFO - 'MAIL': '/var/mail/cltbld', 21:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:32 INFO - 'MOZ_NO_REMOTE': '1', 21:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:32 INFO - 'NO_EM_RESTART': '1', 21:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:32 INFO - 'PWD': '/builds/slave/test', 21:28:32 INFO - 'SHELL': '/bin/bash', 21:28:32 INFO - 'SHLVL': '1', 21:28:32 INFO - 'TERM': 'linux', 21:28:32 INFO - 'TMOUT': '86400', 21:28:32 INFO - 'USER': 'cltbld', 21:28:32 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:32 INFO - '_': '/tools/buildbot/bin/python'} 21:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:32 INFO - Downloading/unpacking jsonschema==2.5.1 21:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:32 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 21:28:32 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 21:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:28:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:35 INFO - Downloading functools32-3.2.3-2.tar.gz 21:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:28:35 INFO - warning: no files found matching '*.txt' 21:28:35 INFO - no previously-included directories found matching 'build' 21:28:35 INFO - no previously-included directories found matching 'dist' 21:28:35 INFO - no previously-included directories found matching '.git*' 21:28:35 INFO - Installing collected packages: jsonschema, functools32 21:28:35 INFO - Running setup.py install for functools32 21:28:35 INFO - warning: no files found matching '*.txt' 21:28:35 INFO - no previously-included directories found matching 'build' 21:28:35 INFO - no previously-included directories found matching 'dist' 21:28:35 INFO - no previously-included directories found matching '.git*' 21:28:35 INFO - Successfully installed jsonschema functools32 21:28:35 INFO - Cleaning up... 21:28:35 INFO - Return code: 0 21:28:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:28:35 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')]} 21:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:35 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')]} 21:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:35 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 21:28:35 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 21:28:35 INFO - Using env: {'DISPLAY': ':0', 21:28:35 INFO - 'HOME': '/home/cltbld', 21:28:35 INFO - 'LANG': 'en_US.UTF-8', 21:28:35 INFO - 'LANGUAGE': 'en_US:en', 21:28:35 INFO - 'LOGNAME': 'cltbld', 21:28:35 INFO - 'MAIL': '/var/mail/cltbld', 21:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:35 INFO - 'MOZ_NO_REMOTE': '1', 21:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:35 INFO - 'NO_EM_RESTART': '1', 21:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:35 INFO - 'PWD': '/builds/slave/test', 21:28:35 INFO - 'SHELL': '/bin/bash', 21:28:35 INFO - 'SHLVL': '1', 21:28:35 INFO - 'TERM': 'linux', 21:28:35 INFO - 'TMOUT': '86400', 21:28:35 INFO - 'USER': 'cltbld', 21:28:35 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:35 INFO - '_': '/tools/buildbot/bin/python'} 21:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:28:35 INFO - Cleaning up... 21:28:35 INFO - Return code: 0 21:28:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:28:35 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')]} 21:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:35 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')]} 21:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:35 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 21:28:35 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 21:28:35 INFO - Using env: {'DISPLAY': ':0', 21:28:35 INFO - 'HOME': '/home/cltbld', 21:28:35 INFO - 'LANG': 'en_US.UTF-8', 21:28:35 INFO - 'LANGUAGE': 'en_US:en', 21:28:35 INFO - 'LOGNAME': 'cltbld', 21:28:35 INFO - 'MAIL': '/var/mail/cltbld', 21:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:35 INFO - 'MOZ_NO_REMOTE': '1', 21:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:35 INFO - 'NO_EM_RESTART': '1', 21:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:35 INFO - 'PWD': '/builds/slave/test', 21:28:35 INFO - 'SHELL': '/bin/bash', 21:28:35 INFO - 'SHLVL': '1', 21:28:35 INFO - 'TERM': 'linux', 21:28:35 INFO - 'TMOUT': '86400', 21:28:35 INFO - 'USER': 'cltbld', 21:28:35 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:35 INFO - '_': '/tools/buildbot/bin/python'} 21:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:35 INFO - Downloading/unpacking blobuploader==1.2.4 21:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:35 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 21:28:35 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 21:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:37 INFO - Downloading blobuploader-1.2.4.tar.gz 21:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:28:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:28:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:38 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 21:28:38 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 21:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:38 INFO - Downloading docopt-0.6.1.tar.gz 21:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:28:38 INFO - Installing collected packages: blobuploader, requests, docopt 21:28:38 INFO - Running setup.py install for blobuploader 21:28:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:28:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:28:38 INFO - Running setup.py install for requests 21:28:38 INFO - Running setup.py install for docopt 21:28:39 INFO - Successfully installed blobuploader requests docopt 21:28:39 INFO - Cleaning up... 21:28:39 INFO - Return code: 0 21:28:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 21:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.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 21:28:39 INFO - Using env: {'DISPLAY': ':0', 21:28:39 INFO - 'HOME': '/home/cltbld', 21:28:39 INFO - 'LANG': 'en_US.UTF-8', 21:28:39 INFO - 'LANGUAGE': 'en_US:en', 21:28:39 INFO - 'LOGNAME': 'cltbld', 21:28:39 INFO - 'MAIL': '/var/mail/cltbld', 21:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:39 INFO - 'MOZ_NO_REMOTE': '1', 21:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:39 INFO - 'NO_EM_RESTART': '1', 21:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:39 INFO - 'PWD': '/builds/slave/test', 21:28:39 INFO - 'SHELL': '/bin/bash', 21:28:39 INFO - 'SHLVL': '1', 21:28:39 INFO - 'TERM': 'linux', 21:28:39 INFO - 'TMOUT': '86400', 21:28:39 INFO - 'USER': 'cltbld', 21:28:39 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:39 INFO - '_': '/tools/buildbot/bin/python'} 21:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:28:39 INFO - Running setup.py (path:/tmp/pip-0RmN2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:28:39 INFO - Running setup.py (path:/tmp/pip-yx8_ZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:28:39 INFO - Running setup.py (path:/tmp/pip-iZXTxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:28:39 INFO - Running setup.py (path:/tmp/pip-HhMYb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:28:39 INFO - Running setup.py (path:/tmp/pip-2Eb7gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:28:39 INFO - Running setup.py (path:/tmp/pip-NBPjKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:28:39 INFO - Running setup.py (path:/tmp/pip-vAb4kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:28:39 INFO - Running setup.py (path:/tmp/pip-mZn6qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:28:39 INFO - Running setup.py (path:/tmp/pip-7wQfZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:28:40 INFO - Running setup.py (path:/tmp/pip-etOk03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:28:40 INFO - Running setup.py (path:/tmp/pip-Pzc3UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:28:40 INFO - Running setup.py (path:/tmp/pip-ieasS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:28:40 INFO - Running setup.py (path:/tmp/pip-qCSHKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:28:40 INFO - Running setup.py (path:/tmp/pip-LuDVRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:40 INFO - Running setup.py (path:/tmp/pip-Y7lTsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:28:40 INFO - Running setup.py (path:/tmp/pip-tm37gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:28:40 INFO - Running setup.py (path:/tmp/pip-u_zzjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:28:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:28:40 INFO - Running setup.py install for manifestparser 21:28:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:28:40 INFO - Running setup.py install for mozcrash 21:28:40 INFO - Running setup.py install for mozdebug 21:28:40 INFO - Running setup.py install for mozdevice 21:28:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:28:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for mozfile 21:28:41 INFO - Running setup.py install for mozhttpd 21:28:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for mozinfo 21:28:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for mozInstall 21:28:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for mozleak 21:28:41 INFO - Running setup.py install for mozlog 21:28:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for moznetwork 21:28:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for mozprocess 21:28:41 INFO - Running setup.py install for mozprofile 21:28:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:28:41 INFO - Running setup.py install for mozrunner 21:28:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:28:42 INFO - Running setup.py install for mozscreenshot 21:28:42 INFO - Running setup.py install for moztest 21:28:42 INFO - Running setup.py install for mozversion 21:28:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:28:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:28:42 INFO - Cleaning up... 21:28:42 INFO - Return code: 0 21:28:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28:42 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')]} 21:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:42 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')]} 21:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:42 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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:42 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 21:28:42 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 21:28:42 INFO - Using env: {'DISPLAY': ':0', 21:28:42 INFO - 'HOME': '/home/cltbld', 21:28:42 INFO - 'LANG': 'en_US.UTF-8', 21:28:42 INFO - 'LANGUAGE': 'en_US:en', 21:28:42 INFO - 'LOGNAME': 'cltbld', 21:28:42 INFO - 'MAIL': '/var/mail/cltbld', 21:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:42 INFO - 'MOZ_NO_REMOTE': '1', 21:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:42 INFO - 'NO_EM_RESTART': '1', 21:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:42 INFO - 'PWD': '/builds/slave/test', 21:28:42 INFO - 'SHELL': '/bin/bash', 21:28:42 INFO - 'SHLVL': '1', 21:28:42 INFO - 'TERM': 'linux', 21:28:42 INFO - 'TMOUT': '86400', 21:28:42 INFO - 'USER': 'cltbld', 21:28:42 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:42 INFO - '_': '/tools/buildbot/bin/python'} 21:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:28:42 INFO - Running setup.py (path:/tmp/pip-JQshp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:28:42 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)) 21:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:28:42 INFO - Running setup.py (path:/tmp/pip-bBzI4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:28:42 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)) 21:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:28:42 INFO - Running setup.py (path:/tmp/pip-dXmHzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:28:42 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)) 21:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:28:42 INFO - Running setup.py (path:/tmp/pip-mxW7Mi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:28:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:28:42 INFO - Running setup.py (path:/tmp/pip-5Bak6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:28:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:28:42 INFO - Running setup.py (path:/tmp/pip-A5LFFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:28:43 INFO - Running setup.py (path:/tmp/pip-GVcHlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:28:43 INFO - Running setup.py (path:/tmp/pip-FbbIpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:28:43 INFO - Running setup.py (path:/tmp/pip-ksnM6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:28:43 INFO - Running setup.py (path:/tmp/pip-ZiUxM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:28:43 INFO - Running setup.py (path:/tmp/pip-ofz2Fe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:28:43 INFO - Running setup.py (path:/tmp/pip-rES7KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:28:43 INFO - Running setup.py (path:/tmp/pip-ySQf3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:28:43 INFO - Running setup.py (path:/tmp/pip-kfTxXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:43 INFO - Running setup.py (path:/tmp/pip-DRXndf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:43 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)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:28:43 INFO - Running setup.py (path:/tmp/pip-VBPWlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:28:43 INFO - Running setup.py (path:/tmp/pip-BTlRlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:28:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:45 INFO - Downloading blessings-1.6.tar.gz 21:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:28:45 INFO - Installing collected packages: blessings 21:28:45 INFO - Running setup.py install for blessings 21:28:46 INFO - Successfully installed blessings 21:28:46 INFO - Cleaning up... 21:28:46 INFO - Return code: 0 21:28:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:28:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:28:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:28:46 INFO - Reading from file tmpfile_stdout 21:28:46 INFO - Current package versions: 21:28:46 INFO - argparse == 1.2.1 21:28:46 INFO - blessings == 1.6 21:28:46 INFO - blobuploader == 1.2.4 21:28:46 INFO - docopt == 0.6.1 21:28:46 INFO - functools32 == 3.2.3-2 21:28:46 INFO - jsonschema == 2.5.1 21:28:46 INFO - manifestparser == 1.1 21:28:46 INFO - mozInstall == 1.12 21:28:46 INFO - mozcrash == 1.0 21:28:46 INFO - mozdebug == 0.1 21:28:46 INFO - mozdevice == 0.49 21:28:46 INFO - mozfile == 1.2 21:28:46 INFO - mozhttpd == 0.7 21:28:46 INFO - mozinfo == 0.9 21:28:46 INFO - mozleak == 0.1 21:28:46 INFO - mozlog == 3.4 21:28:46 INFO - moznetwork == 0.27 21:28:46 INFO - mozprocess == 0.25 21:28:46 INFO - mozprofile == 0.28 21:28:46 INFO - mozrunner == 6.13 21:28:46 INFO - mozscreenshot == 0.1 21:28:46 INFO - mozsystemmonitor == 0.3 21:28:46 INFO - moztest == 0.8 21:28:46 INFO - mozversion == 1.4 21:28:46 INFO - psutil == 3.1.1 21:28:46 INFO - requests == 1.2.3 21:28:46 INFO - wsgiref == 0.1.2 21:28:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28:46 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')]} 21:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:46 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')]} 21:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 21:28:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.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 21:28:46 INFO - Using env: {'DISPLAY': ':0', 21:28:46 INFO - 'HOME': '/home/cltbld', 21:28:46 INFO - 'LANG': 'en_US.UTF-8', 21:28:46 INFO - 'LANGUAGE': 'en_US:en', 21:28:46 INFO - 'LOGNAME': 'cltbld', 21:28:46 INFO - 'MAIL': '/var/mail/cltbld', 21:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:46 INFO - 'MOZ_NO_REMOTE': '1', 21:28:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:46 INFO - 'NO_EM_RESTART': '1', 21:28:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:46 INFO - 'PWD': '/builds/slave/test', 21:28:46 INFO - 'SHELL': '/bin/bash', 21:28:46 INFO - 'SHLVL': '1', 21:28:46 INFO - 'TERM': 'linux', 21:28:46 INFO - 'TMOUT': '86400', 21:28:46 INFO - 'USER': 'cltbld', 21:28:46 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:46 INFO - '_': '/tools/buildbot/bin/python'} 21:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:28:46 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)) 21:28:46 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)) 21:28:46 INFO - Cleaning up... 21:28:46 INFO - Return code: 0 21:28:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:28:46 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')]} 21:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:46 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')]} 21:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x276d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27aaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, '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': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', '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 21:28:46 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 21:28:46 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 21:28:46 INFO - Using env: {'DISPLAY': ':0', 21:28:46 INFO - 'HOME': '/home/cltbld', 21:28:46 INFO - 'LANG': 'en_US.UTF-8', 21:28:46 INFO - 'LANGUAGE': 'en_US:en', 21:28:46 INFO - 'LOGNAME': 'cltbld', 21:28:46 INFO - 'MAIL': '/var/mail/cltbld', 21:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:46 INFO - 'MOZ_NO_REMOTE': '1', 21:28:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:46 INFO - 'NO_EM_RESTART': '1', 21:28:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:46 INFO - 'PWD': '/builds/slave/test', 21:28:46 INFO - 'SHELL': '/bin/bash', 21:28:46 INFO - 'SHLVL': '1', 21:28:46 INFO - 'TERM': 'linux', 21:28:46 INFO - 'TMOUT': '86400', 21:28:46 INFO - 'USER': 'cltbld', 21:28:46 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:46 INFO - '_': '/tools/buildbot/bin/python'} 21:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:28:46 INFO - Cleaning up... 21:28:46 INFO - Return code: 0 21:28:46 INFO - Running post-action listener: _resource_record_post_action 21:28:46 INFO - Running post-action listener: _start_resource_monitoring 21:28:46 INFO - Starting resource monitoring. 21:28:46 INFO - [mozharness: 2017-02-21 05:28:46.814449Z] Finished create-virtualenv step (success) 21:28:46 INFO - [mozharness: 2017-02-21 05:28:46.814967Z] Running install step. 21:28:46 INFO - Running pre-action listener: _resource_record_pre_action 21:28:46 INFO - Running main action method: install 21:28:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:28:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:28:47 INFO - Reading from file tmpfile_stdout 21:28:47 INFO - Detecting whether we're running mozinstall >=1.0... 21:28:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:28:47 INFO - Reading from file tmpfile_stdout 21:28:47 INFO - Output received: 21:28:47 INFO - Usage: mozinstall [options] installer 21:28:47 INFO - Options: 21:28:47 INFO - -h, --help show this help message and exit 21:28:47 INFO - -d DEST, --destination=DEST 21:28:47 INFO - Directory to install application into. [default: 21:28:47 INFO - "/builds/slave/test"] 21:28:47 INFO - --app=APP Application being installed. [default: firefox] 21:28:47 INFO - mkdir: /builds/slave/test/build/application 21:28:47 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'] 21:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:29:03 INFO - Reading from file tmpfile_stdout 21:29:03 INFO - Output received: 21:29:03 INFO - /builds/slave/test/build/application/firefox/firefox 21:29:03 INFO - Running post-action listener: _resource_record_post_action 21:29:03 INFO - [mozharness: 2017-02-21 05:29:03.723310Z] Finished install step (success) 21:29:03 INFO - [mozharness: 2017-02-21 05:29:03.723588Z] Running run-tests step. 21:29:03 INFO - Running pre-action listener: _resource_record_pre_action 21:29:03 INFO - Running main action method: run_tests 21:29:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:29:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:29:03 INFO - Python 2.7.3 21:29:03 INFO - Return code: 0 21:29:03 INFO - grabbing minidump binary from tooltool 21:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a08400>, '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 0x2a093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a09840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:29:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:29:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:29:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:29:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:29:03 INFO - Return code: 0 21:29:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:29:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:29:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:29:03 INFO - ENV: RUST_BACKTRACE is now 1 21:29:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:29:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:29:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 21:29:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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 21:29:03 INFO - Using env: {'DISPLAY': ':0', 21:29:03 INFO - 'HOME': '/home/cltbld', 21:29:03 INFO - 'LANG': 'en_US.UTF-8', 21:29:03 INFO - 'LANGUAGE': 'en_US:en', 21:29:03 INFO - 'LOGNAME': 'cltbld', 21:29:03 INFO - 'MAIL': '/var/mail/cltbld', 21:29:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:29:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:03 INFO - 'MOZ_NO_REMOTE': '1', 21:29:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:29:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:03 INFO - 'NO_EM_RESTART': '1', 21:29:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:03 INFO - 'PWD': '/builds/slave/test', 21:29:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:29:03 INFO - 'RUST_BACKTRACE': '1', 21:29:03 INFO - 'SHELL': '/bin/bash', 21:29:03 INFO - 'SHLVL': '1', 21:29:03 INFO - 'TERM': 'linux', 21:29:03 INFO - 'TMOUT': '86400', 21:29:03 INFO - 'USER': 'cltbld', 21:29:03 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802', 21:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:03 INFO - '_': '/tools/buildbot/bin/python'} 21:29:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 21:29:03 INFO - mozversion application_buildid: 20170221042705 21:29:03 INFO - mozversion application_changeset: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 21:29:03 INFO - mozversion application_display_name: Nightly 21:29:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:29:03 INFO - mozversion application_name: Firefox 21:29:03 INFO - mozversion application_remotingname: firefox 21:29:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:29:03 INFO - mozversion application_vendor: Mozilla 21:29:03 INFO - mozversion application_version: 54.0a1 21:29:03 INFO - mozversion platform_buildid: 20170221042705 21:29:03 INFO - mozversion platform_changeset: 276bd9ed3dbf85f4a6dac67df54a5f2631804de5 21:29:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:29:03 INFO - mozversion platform_version: 54.0a1 21:29:03 INFO - using testdate: 1487654943 21:29:03 INFO - actual date: 1487654943 21:29:03 INFO - starting webserver on 'localhost:55175' 21:29:03 INFO - SUITE-START | Running 2 tests 21:29:03 INFO - TEST-START | dromaeo_css 21:29:03 INFO - operating with platform_type : linux_ 21:29:03 INFO - Initialising browser for dromaeo_css test... 21:29:04 INFO - TEST-INFO | started process 24109 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYevpES/profile http://localhost:55175/getInfo.html) 21:29:07 INFO - PROCESS | 24109 | Iterating over profile /tmp/tmpYevpES/profile 21:29:08 INFO - PROCESS | 24109 | __metrics Screen width/height:1600/1200 21:29:08 INFO - PROCESS | 24109 | colorDepth:24 21:29:08 INFO - PROCESS | 24109 | Browser inner width/height: 1024/697 21:29:08 INFO - PROCESS | 24109 | __metrics 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/pluginreg.dat = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/thumbnails = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/permissions.sqlite = Mon Feb 20 2017 21:29:05 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/bookmarkbackups = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/places.sqlite-shm = Mon Feb 20 2017 21:29:05 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/startupCache = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/user.js = Mon Feb 20 2017 21:29:03 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/safebrowsing = Mon Feb 20 2017 21:29:08 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/localstore.rdf = Mon Feb 20 2017 21:28:21 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/crashes = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/frequencyCap.json = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/cookies.sqlite = Mon Feb 20 2017 21:29:07 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/gmp = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/extensions.ini = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/.parentlock = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/cert8.db = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/addons.json = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/minidumps = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/places.sqlite = Mon Feb 20 2017 21:29:08 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/prefs.js = Mon Feb 20 2017 21:28:21 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/extensions.json = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/sessionCheckpoints.json = Mon Feb 20 2017 21:29:08 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/directoryLinks.json = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/cache2 = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/places.sqlite-wal = Mon Feb 20 2017 21:29:08 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/blocklist.xml = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/key3.db = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/compatibility.ini = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/content-prefs.sqlite = Mon Feb 20 2017 21:29:06 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/secmod.db = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | Stat failure 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/mimeTypes.rdf = Mon Feb 20 2017 21:29:07 GMT-0800 (PST) 21:29:08 INFO - PROCESS | 24109 | No birth date. Using mtime. 21:29:08 INFO - PROCESS | 24109 | Using date: /tmp/tmpYevpES/profile/extensions = Mon Feb 20 2017 21:29:04 GMT-0800 (PST) 21:29:09 INFO - PROCESS | 24109 | 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" 21:29:09 INFO - PROCESS | 24109 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:29:10 INFO - TEST-INFO | 24109: exit 0 21:29:10 INFO - Browser initialized. 21:29:10 INFO - Running cycle 1/1 for dromaeo_css test... 21:29:10 INFO - TEST-INFO | started process 24204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYevpES/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:29:22 INFO - PROCESS | 24204 | RSS: Main: 142880768 21:29:22 INFO - PROCESS | 24204 | 21:32:23 INFO - PROCESS | 24204 | Cycle 1(1): loaded http://localhost:55175/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55175/tests/dromaeo/cssquery-ext.html) 21:32:25 INFO - PROCESS | 24204 | RSS: Main: 277749760 21:32:25 INFO - PROCESS | 24204 | 21:34:12 INFO - PROCESS | 24204 | ************************* 21:34:12 INFO - PROCESS | 24204 | A coding exception was thrown and uncaught in a Task. 21:34:12 INFO - PROCESS | 24204 | 21:34:12 ERROR - PROCESS | 24204 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:34:12 INFO - PROCESS | 24204 | Full stack: 21:34:12 INFO - PROCESS | 24204 | ************************* 21:35:27 INFO - PROCESS | 24204 | Cycle 1(1): loaded http://localhost:55175/tests/dromaeo/cssquery-ext.html (next: http://localhost:55175/tests/dromaeo/cssquery-jquery.html) 21:35:28 INFO - PROCESS | 24204 | RSS: Main: 244584448 21:35:28 INFO - PROCESS | 24204 | 21:38:30 INFO - PROCESS | 24204 | Cycle 1(1): loaded http://localhost:55175/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55175/tests/dromaeo/cssquery-mootools.html) 21:38:30 INFO - PROCESS | 24204 | RSS: Main: 178348032 21:38:30 INFO - PROCESS | 24204 | 21:41:32 INFO - PROCESS | 24204 | Cycle 1(1): loaded http://localhost:55175/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55175/tests/dromaeo/cssquery-prototype.html) 21:41:33 INFO - PROCESS | 24204 | RSS: Main: 221356032 21:41:33 INFO - PROCESS | 24204 | 21:44:36 INFO - PROCESS | 24204 | Cycle 1(1): loaded http://localhost:55175/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55175/tests/dromaeo/cssquery-yui.html) 21:44:36 INFO - PROCESS | 24204 | RSS: Main: 187686912 21:44:36 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | Cycle 1(1): loaded http://localhost:55175/tests/dromaeo/cssquery-yui.html (next: http://localhost:55175/tests/dromaeo/cssquery-dojo.html) 21:47:39 INFO - PROCESS | 24204 | RSS: Main: 283361280 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | __start_tp_report 21:47:39 INFO - PROCESS | 24204 | _x_x_mozilla_page_load 21:47:39 INFO - PROCESS | 24204 | _x_x_mozilla_page_load_details 21:47:39 INFO - PROCESS | 24204 | |i|pagename|runs| 21:47:39 INFO - PROCESS | 24204 | |0;dojo.html;3081.2913030003097;3105.3013072058784;3116.47020006599;3152.6689697581737;3173.714365707086;5096.872578185543;5103.081445339837;5158.587313014959;5174.172132458807;5202.21966704994;5178.948210517894;5190.610704197182;5272.103742363798;5280.2343660169345;5316.255724198615;4698.718076915387;4704.905901881961;4786.545278198566;4787.904241915159;4794.400699912511;4794;4813;4823.831165909194;4825.855224343275;4828.130936431441;4540.931886021729;4562.110388474249;4565.223911934946;4686.5782079613;4782.019685964387;4723.456802467692;4760.785764640599;4775.188218002948;4808.422989241279;4809.1824389853455;4720.504347043546;4735.076660051292;4763.237881938908;4768.332433459318;4771.522847715235;5005.324281222025;5306.416294207634;5314.867128321823;5327.413984461706;5379.381371142291;5421.945780542223;5484.369297530795;5488.451154884519;5490.972545137249;5502.642328248651;5551.8889622207735;5552.86117847053;5558.499735023827;5560.77756889724;5564.360098588633;5124.564412024983;5185.429602743658;5188.066148093341;5193.143131383317;5276.34045744282;5783.479486846204;5824.708764561755;5871.7651293947765;5875.50058245054;5881.029630110984;5281.445448227958;5313.867153321198;5324.520793128637;5614.382417934024;5661.46216109469;5459.863503412446;5466.070767969455;5468.644538105011;5477.45225477457;5479.369872464637;5547.972260138754;5552.250446189793;5581.302337207849;5623.550115990711;5632.04255276604;5464.480874317013;5500.7524661390335;5614.803481878114;5619.662820230799;5621.662700237999;4331.740095594275;4341.522432532419;4377;4393.450818647669;4414.381986521889;3945.033466800652;3952.209558088394;3958.050067983663;3988.581198974124;3989.7406668567032;5410.702411367412;5427;5428.375736790241;5430.2679447655455;5478.726063696799;5487.533559647395;5541.224228607998;5546.057170280981;5599.860003499945;5752;5222.92165617516;5272.393674727378;5295.841124766263;5299.205119232069;5328.946710532922;4810.855674329776;4840.201366774443;4874.049560335771;4883.096627123993;4890.731009794425;5906.527477801766;5906.763729450774;5911.586188966817;5914;5960.225170727777;5348.171033489815;5354.9464505354445;5362.168863826113;5601.355844077983;5657.688827114548;5656.406077361818;5659;5859.765609375577;5921.733521991607;5957.16599676037;5634.83095507119;5666.631668941505;5676.035074037506;5680.091185410314;5680.573956953328;5662.122371032497;5719.914201287067;5722.141678748221;5726.284214473191;5727.055035919193;5360.544353729977;5581;5581.74882130297;5584.329880414376;5589.133684278943;5352.036633405869;5462.317210348707;5528.502434780728;5540.556755459473;5554.222408862682;5487.478689524489;5487.6981766003255;5491.643043202179;5498.257735205696;5501.78960628677;5480.6437581557075;5527.723613819373;5531.0873705839695;5533.806316778893;5570.080936645408;5255.185446255837;5307.761150748149;5308.628396012242;5368.060589396762;5382.569394448356 21:47:39 INFO - PROCESS | 24204 | |1;ext.html;559.5747232103603;585.5666806563145;605.3401792046672;611.0223642172523;613.4724137241972;29860.701392986062;30479.847600761994;33483.832580837094;42705.57294427055;43887.34168987468;46737.766311168394;47030.76484617581;47085.05829883409;47254.527454725445;47354.289685654745;9162.633494660222;9394.530273486333;9395;9397.342186046979;9398.34211605188;7395;7577.659005344773;9106.089391060907;9217;10526.894731052706;10539.84190237147;11124.221304508688;11131.554737810478;11131.554737810478;11132.61035863745;10968.23222374434;11093.944530277297;11104.389258590774;11111.055560277386;11117.277376970553;10648.733781655443;10682.198835087322;10686.51910664022;10686.51910664022;10736.892631073746;44227.33658995118;44421.55578444206;44561.33158002632;44600;44642.55357446417;3890.9027274318087;3972.245273398045;3998.900027499307;3999.4000899864964;4107.075907920724;31189.220269493217;31688.366232675216;31874.203144921332;32117.51823722646;33390.83304583486;3900.7659540427667;4021.155557332934;4057.736247143926;4091;4166.416701661769;326100.3694981534;328923;329376;329720;329898;60482.39517604768;60726.4818379544;60984.39015609876;60985.695071525246;61039.69480152571;63164;63177.36822631715;63270.091238595174;63292.36707632957;63401;15996.360145594046;16339.918300408659;16340.75488867668;16341.428050018192;16348.10085445312;11778.646640600795;11782.823257651142;11784.116191285688;11787.941060294643;11792.764144717057;14747.705045899236;15013.24933753308;16610.6677866442;16905.23926423314;16914.83085169157;6197.938020619736;6907;7014.614196219155;7020.05229294049;7035.788926332219;899.3299991506238;901.2789768185426;935.756703257158;939.4832841936908;957.760559860035;27424.45151096967;28158;28882.133535993955;30271.243218919262;30877;1051.321897376188;1063.6915294564644;1072.951717172729;1075.1828610256262;1109.916756243285;13697.178169309675;14452.053768113821;14683.632909177357;14842.777358339634;15168.696626067638;431967;436021;436583;437080;438422.69968705846;52230.47769522332;52428;52463.475365246624;52529;52530.999999999236;1355.6746380868522;1390.728807882453;1411.498917884157;1412.2373918084324;1414.405949501212;25392.492150157264;26076.34809129787;26285;26979.86510067437;27249.31876703098;45242.32136517889;45618.315725263485;45729.771351143034;45810.31284530801;45813.77093114513;331658;333017;333906.96558206587;336086;336150;25182;25540.23379298624;25586.8720656403;25696.614550782127;25779.484410312063;41227.952468279786;41373.37939930843;41643.37534937039;41649.37525937053;42325;34498.482522761675;34641.82679086588;34890.82554587211;34971.65028349684;35016.82491587628;34286.48570271498;34355.14112147217;34489.310213795085;34490.482642759875;34754.652453476156;34355;34767.82616086904;35234.29531409306;35318.82340588281;35555.11112222215;28725;30833;31093.8445307772;31218.409882862852;31342.216444588157;23135.88432057829;23405.180818671266;23413.297601071994;23719.1698290559;23729.16947906815 21:47:39 INFO - PROCESS | 24204 | |2;jquery.html;372.33352299384103;412.3279055140123;428.7513242319455;430.4554738256107;432.14866712576213;12813.423395947182;14302.141871487705;19076;19566.608667826637;19646.115924783382;21127.366179014614;21421;21543.353699389045;21672;21691.783082169175;5754.769809207627;5861.824145275648;5997.730102145414;6079.969600151993;6146.29317628474;3588.6411358864034;6133.987891997837;6165.414285642857;6175.907361389561;6182.969085154568;3730.7201959852982;3961.564227934925;4084.101497670508;6665.166854143232;6702.329767023308;6517.120188774502;6783.6269005204695;6785.660716964132;6788.8302792430095;6794.796156115324;6003.099535069731;6022;6036.396360363972;6065.090236464522;6069.271687397496;13872.93063534686;13965.79051314231;14196.858031419657;14203.57389278313;14461.78307325391;2941.132365952028;2944.6319210098736;2992.2070651277427;2997.3405850712807;3002.6246719160104;10477.266591338534;10538.315009524356;11073.114150867912;11192.776144477146;11987.041036717043;1743.1119763214879;1752.9649407011789;1767.4962635648778;1770.6281680847037;1820.1263393571028;88588;89149.55425222898;89741;89815;90962.5451872743;11827;11877.762444751057;11985.220960637706;12043.819342709867;12285.938570307091;17099.658006839793;17199;17225.22486488086;17239.22423490946;17257.65484690299;8206.507609543447;8520.57397130141;8550.187732165434;8564.3148548116;8610.64184967863;7722.459427839997;7722.961385193038;7937.126916039231;8006;8016.639251233709;5078.289039534468;5140.7943682253035;5166.741662916839;5195.324607801037;5213.582913366921;2110.4512826665177;2159.157928407922;2164.5238047629496;2196.6375548034716;2199.2412617646887;814.1329484099415;836.3810780022861;849.3502470609989;851.5657014922434;861.9138086191331;18786;19292.1318540666;19376.321828735927;19394.806051939584;19938.1027853744;839.9748007559783;842.4229402859061;846.0725888425992;846.5428668519054;846.6655671009917;9946.254030947708;10650;10766.300190487593;10791.892081079088;10849.403282819521;421413;424162;428108;586118;601274;104530.47734761277;108134.45932770286;110373.44813275883;111775;113118.43440782743;1040.5889673578895;1045.9320144190604;1087.8640169978753;1097.676185525256;1101.5098281264761;17206.397776077778;17668.204930778404;17952.371666991592;18144.637107257517;18282.72575911389;41700.1659966805;42054.57945420629;42725;43222;43404.348934765374;60179.39820601738;60305.52595675954;61145.38854611575;61454.69272653787;62278.32912531488;8477.703280385156;8538.91461085406;8898.243649289883;8950.328725345755;9027.55559110539;12738.299393533445;13107.148035378052;13174.275414852274;13381.66545836362;13631.65920851947;11447.599334023269;11467.369294688539;11476.942615286871;11674.883251167379;11963.401829908644;11603.5938742147;11680.123990700902;11733.35466549348;11784.528618855493;11839.76320473603;10818.891811081789;11307.773844523228;11484.368359740294;11544.769104618028;11700;8549;8705.042445331135;8731;8793.252573531321;8824.735257942271;10262.846057309296;10785.730356741144;11101.888981110085;11498.54005839757;12426.62720118398 21:47:39 INFO - PROCESS | 24204 | |3;mootools.html;1388.2503448138007;1391.5825252424277;1392.470861072792;1401.306353355089;1409.18971591335;7744.1868603796565;8240.7939801505;8250.628721707522;8263.462874913126;8269.173082691736;7776.650050747716;8177.79555511111;8207.261346478816;8372.665093396272;8384.413091083627;2186.7813218678166;2206.9779302206935;2211.7788221177916;2214.1032881682945;2214.180753121347;1913.1773337464886;2170.7395112586432;2202.273249827553;2203.6914831923464;2209.3151123151874;1911.9904400477978;2003.188708573023;2072.8134467897885;2075.2217918280644;2076.4393613724287;2114.8625339352893;2117.12139462123;2134.3810295014428;2135.4020874155262;2141.7858214178614;2098.380977611608;2173.5870184665023;2173.7608863025052;2181.040342249421;2182.2907555044676;8322.375821813328;8360.665573377057;8366.205210504992;8368.414211005233;8385.161483851627;4787.186178349689;4878.4633690294395;4892.559669629751;4896.510348965111;4897.118518666639;3027.544720595216;3140.7173354398215;3214.758893083028;3218.742500599946;3233.3533293341193;1282.2434763489493;1282.634449181988;1308.9018323625671;1331.2079312808862;1380.3581334679423;11108.333499990027;11280.717982050432;11309.208355415125;11344.092472602171;11361.772764544745;3918.74528155669;4104.343305071234;4161.687873409506;4172.3532852407925;4221.324588065956;8954.507502087448;8965.282777377794;8981.371304008788;8987.370884038055;9005.279577633773;4889.3888263967;4891.926621100686;4892.633052521004;4894.828680996148;4896.4124305082905;4370.7159034662645;4385.583369579885;4497.437820272466;4505.481869584974;4547.977260113678;3391.1522119470133;3429.0741499794917;3467.497212904115;3491.3366460372954;3522.859085636546;1018.7453136715822;1024.2164743970868;1038.6987773545738;1040.552562398161;1042.6663467690419;722.2741145149091;729.824842037907;744.192551082082;751.6091632351256;776.7902666280073;5148.253503242009;5243.370795504564;5335.466453354634;5445.782168713286;5546.306711661043;607.1803065861052;621.0621960839165;622.5393209025289;626.9279032911273;761.7410080572632;3510.227749895019;3560.786352818839;3742.55089389275;3803.6196380361744;3806.410006449005;9412.529373531295;9965.601375945025;9969.252306077073;9978.052085051908;9983.201343892471;9672.613095476243;9683.322167448352;9856.704298871045;9873.259505537113;9897.307188496736;7657.7319793808065;7676.53940763556;7739.639409979897;7740.187280335596;7744.690212391554;5246.186841039645;5272.525472707474;5285.365756109291;5367.409584945574;5472.26124473207;8648.264897483532;8760.474371537728;8801.691940782042;8818.250448711931;8888;8930.910690893268;9061.77345566366;9103.49930753815;9160.633574656938;9174.403663761834;7095.580883823152;7178.138623365229;7208.675609597476;7218.747343842941;7221.927780722336;7693.269139431969;7781.26078022587;7832.921670783448;7861;7872.724454644289;7000.859982800213;7008.369246767816;7018.964905175646;7118.145822501333;7178.425725941807;6863.931360686329;6874.9312506876295;6888.242293347828;7048.823779405556;7098.538594991309;5892.499137573182;5986.4013598639785;6036;6041.516678665608;6044.818655440344;4758.381410416624;4813.78337974799;4836.854894353175;4838.975805120952;5021.372328458942;4860.3195552621955;4869.9026019480125;4945.703257804544;4976.158821236166;5094.057599343985 21:47:39 INFO - PROCESS | 24204 | |4;prototype.html;381.2394273424517;403.74967520137517;407.5944435286889;408.51590864825187;411.29873565570705;5050.671706339094;5146.974265128679;5225.503577160169;5302.469753024696;5345.92609249681;5077.781332480196;5201.297824793652;5217.843464696056;5222.216667499877;5226.425093239741;4150;4155.252054630166;4157.314043182879;4169.457970463851;4176.4570605821355;3837.750546214501;4066.3900414937702;4182.811773470186;4222.9155416891645;4225.915481690365;4081.265372232997;4123.793810309473;4131.925699318183;4145.97927010366;4156.97921510392;4119.876403707894;4120.21715873986;4142.668586513087;4156.376543518466;4166.666666666638;3733.6826369758605;3767.6640482900775;3789.6968242540806;3793.6775374093236;3807.505024346845;4871.951280487186;5055.165897626924;5110.156824124052;5111.565516931066;5129.461406552333;4866.221404575286;4869.9513004870205;4878.146304404304;4885.389326334208;4893.1926232171645;5315.6013299002725;5451.127819548853;5485.067538518421;5488.204210389471;5523;4149.8173020688955;4177.143685544456;4180.853670121531;4189.183109293689;4213.346931225665;5663.207150998864;5672.744726487318;5694.658320500783;5697.287839020122;5726.198332233491;4310.28880234764;4326.459192600925;4332.501762297313;4343.087951530214;4357.934630980538;4167.729097608646;4320.870373888788;4335.631471324973;4349.478062632505;4366.148601022834;4734.455537613218;4803.303520989437;4809.855704328876;4856.22747697088;4859.392575928009;5485.807996720176;5487.478689524489;5514.944850551524;5518.53092487135;5528.419515950848;1388.1532265318149;1457.3806132393902;1457.5845883923032;1460.350144185827;1460.8094403061684;1035.2080658296409;1067.1622776120864;1070.341739829999;1077.224398433127;1086.2830531848942;5092.115917110693;5111.897762044739;5120.948790512121;5129.358830146231;5150.175971844487;5443.265159203536;5448.891022179613;5459.972700136474;5461.781528738806;5507.614466987273;4814.927776083362;4922.532359425849;5083.847484575469;5096.763555142511;5146.614003949719;2871.6410448693914;2871.655401351851;2877.1800036989357;2888.277930517371;2896.218021134282;5698.430156984268;5713.428657134254;5717.456841600041;5720.68536230503;5728.856778580569;5379.252431355649;5466.922569423594;5541.035545982961;5596.300462442195;5602.215689803431;5610.5231055360755;5699.572532060112;5702.857428564236;5705.600607957486;5723.141528770718;5281.471852814688;5401.540869026019;5403.865188310341;5427.267318911896;5436.782528698966;5571.303587051619;5585.245991791056;5585.385607583082;5594.321703489019;5604.467575580314;5827.621204621686;5856.3558008618165;5890.6760128191645;5903.380145084876;5914.881702366015;3949.815055483401;4071.6742660586488;4077.89805254871;4082.7958602069184;4089.117172393515;5551.861203469945;5557.5553955682635;5563.805266815642;5570.470805273492;5609.775608975595;5433.701146436984;5442.591805614674;5453.345598528202;5464.316960379952;5466.234727138283;5202.557782588522;5429.647072940246;5467.535259502828;5473.2063850742215;5494.532964698045;5456.236126942311;5460.2800117962715;5475.397706252229;5480.861949228307;5497.725113744217;5297.920531192112;5486.341639003185;5510.641808282448;5526.723663816712;5537.5569954402745;5320.733963301742;5346.465353464622;5348.679079255257;5355.651882627617;5362.892742145057 21:47:39 INFO - PROCESS | 24204 | |5;yui.html;710.1336369629054;743.758278559468;805.6455159729722;814.3770015937807;828.8673812190051;15114.244287785608;16192;16232;16241.593960151002;16370.18149092545;14927.32827022784;15172;15180.848191518082;15183.772243416357;15207.619809504768;773.9303462688371;800.5556915911667;819.4796304346723;823.3742355809568;830.0330115415534;829.8049958259804;830.8462934357133;831.4304701279613;831.7837362285788;832.6253186066264;857.0401150711212;862.06465984407;862.3144600042981;862.3316929379749;862.4135587800293;847.3052097280232;848.8726690996339;854.2824027816632;855.482433127958;856.2379482260793;623.7660877171061;624.0171729526014;624.5784095735361;625.2528228766625;625.5214760708062;14598.489052883204;14604.561863144123;14605.78091328631;14857.47998820036;14950.102993820406;12996.740065198737;13647.385867635881;13710;13713.382897769625;13735.313234338342;11522.654320370402;12121.090918181084;12132.818007729891;12219.511219551207;12706.682332941751;3672.320620685155;3674.2467794102145;3811.104390468238;3819.751716138442;3890.2025084857537;71347.64326178387;71372.99999999948;72473.6376318115;73406;74218.62890685567;10148.086672199537;10295.433751143759;10302.896971030345;10447.94776026115;10760.354378737144;17896.55258618519;17913.10434478271;18015.27938882456;18024.459266222035;18026.098695065193;7405.740799072114;7477.513961592479;7669.923300767033;7715.807104822312;7725.150233474314;4186.874393768192;4217.683673724483;4293.463317085364;4298.26929421999;4433.312836510346;3294.258791771832;3361.4957756336744;3380.0704806178496;3384.2385463270566;3388.678075582816;576.2882839692976;580.2398857496693;582.6533212738414;589.4311988930617;589.5165963909639;683.3780960907172;739.903812504371;745.1416762707227;746.6565379925187;757.3903008078549;9357.578908949114;9702.223822094216;9870.555824987892;9958.502074896227;10090.8486372703;705.7247673407376;720.9423246140296;727.5925481730249;732.5128789355126;733.8605664923648;4532.546745325441;4849.854504364875;4930.408350997832;4933.333999910038;4934.9753251233515;73986.6300668504;75358.62320688361;76238;78251;78496;65712;67696.99999999901;67880;68159.3184068163;68575.65712171506;60462.69768651129;60565.69717151386;60779.39220607738;60787.69606152029;60822.69588652117;9255.722328330161;9455.716328510156;9558.235341172829;9575.377600455948;9599.808003840024;41735.165296694504;42308;42497.15005699931;42604.78697606492;43127.35308970431;49371.75314123406;51038;51547.22679159741;51642.483575163766;51814.481855180966;10945.890541094806;11030.889691102986;11187.10503159729;11319.207655464368;11874.643760687193;11399.658010259705;11652.475638596114;11883.702907427038;12163;12465.813012804996;11709.531618735155;11861.644150675493;11914.880851191378;11964.282143071443;12023.939880300542;11593.884061159617;11715.765684686088;12126.757464850478;12452.750944980868;12466.7815755095;11490.827637585277;11985.700357491132;12018.819717704067;12043;12154.209976351509;8877.73366798997;9387.85918211241;9426.717198484057;9583.856242156513;9826.803463930539;8272.875906861522;9695.175910047725;9729.756756081155;9794.412335259907;9905.504724763588 21:47:39 INFO - PROCESS | 24204 | __end_tp_report 21:47:39 INFO - PROCESS | 24204 | __start_cc_report 21:47:39 INFO - PROCESS | 24204 | _x_x_mozilla_cycle_collect,1304 21:47:39 INFO - PROCESS | 24204 | __end_cc_report 21:47:39 INFO - PROCESS | 24204 | __startTimestamp1487656059734__endTimestamp 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | ------- Summary: start ------- 21:47:39 INFO - PROCESS | 24204 | Number of tests: 6 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | [#0] dojo.html Cycles:165 Average:5215.17 Median:5382.57 stddev:576.89 (10.7%) stddev-sans-first:553.84 21:47:39 INFO - PROCESS | 24204 | Values: 3081.3 3105.3 3116.5 3152.7 3173.7 5096.9 5103.1 5158.6 5174.2 5202.2 5178.9 5190.6 5272.1 5280.2 5316.3 4698.7 4704.9 4786.5 4787.9 4794.4 4794.0 4813.0 4823.8 4825.9 4828.1 4540.9 4562.1 4565.2 4686.6 4782.0 4723.5 4760.8 4775.2 4808.4 4809.2 4720.5 4735.1 4763.2 4768.3 4771.5 5005.3 5306.4 5314.9 5327.4 5379.4 5421.9 5484.4 5488.5 5491.0 5502.6 5551.9 5552.9 5558.5 5560.8 5564.4 5124.6 5185.4 5188.1 5193.1 5276.3 5783.5 5824.7 5871.8 5875.5 5881.0 5281.4 5313.9 5324.5 5614.4 5661.5 5459.9 5466.1 5468.6 5477.5 5479.4 5548.0 5552.3 5581.3 5623.6 5632.0 5464.5 5500.8 5614.8 5619.7 5621.7 4331.7 4341.5 4377.0 4393.5 4414.4 3945.0 3952.2 3958.1 3988.6 3989.7 5410.7 5427.0 5428.4 5430.3 5478.7 5487.5 5541.2 5546.1 5599.9 5752.0 5222.9 5272.4 5295.8 5299.2 5328.9 4810.9 4840.2 4874.0 4883.1 4890.7 5906.5 5906.8 5911.6 5914.0 5960.2 5348.2 5354.9 5362.2 5601.4 5657.7 5656.4 5659.0 5859.8 5921.7 5957.2 5634.8 5666.6 5676.0 5680.1 5680.6 5662.1 5719.9 5722.1 5726.3 5727.1 5360.5 5581.0 5581.7 5584.3 5589.1 5352.0 5462.3 5528.5 5540.6 5554.2 5487.5 5487.7 5491.6 5498.3 5501.8 5480.6 5527.7 5531.1 5533.8 5570.1 5255.2 5307.8 5308.6 5368.1 5382.6 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | [#1] ext.html Cycles:180 Average:52513.37 Median:34355.07 stddev:97490.33 (283.8%) stddev-sans-first:97685.78 21:47:39 INFO - PROCESS | 24204 | Values: 559.6 585.6 605.3 611.0 613.5 29860.7 30479.8 33483.8 42705.6 43887.3 46737.8 47030.8 47085.1 47254.5 47354.3 9162.6 9394.5 9395.0 9397.3 9398.3 7395.0 7577.7 9106.1 9217.0 10526.9 10539.8 11124.2 11131.6 11131.6 11132.6 10968.2 11093.9 11104.4 11111.1 11117.3 10648.7 10682.2 10686.5 10686.5 10736.9 44227.3 44421.6 44561.3 44600.0 44642.6 3890.9 3972.2 3998.9 3999.4 4107.1 31189.2 31688.4 31874.2 32117.5 33390.8 3900.8 4021.2 4057.7 4091.0 4166.4 326100.4 328923.0 329376.0 329720.0 329898.0 60482.4 60726.5 60984.4 60985.7 61039.7 63164.0 63177.4 63270.1 63292.4 63401.0 15996.4 16339.9 16340.8 16341.4 16348.1 11778.6 11782.8 11784.1 11787.9 11792.8 14747.7 15013.2 16610.7 16905.2 16914.8 6197.9 6907.0 7014.6 7020.1 7035.8 899.3 901.3 935.8 939.5 957.8 27424.5 28158.0 28882.1 30271.2 30877.0 1051.3 1063.7 1073.0 1075.2 1109.9 13697.2 14452.1 14683.6 14842.8 15168.7 431967.0 436021.0 436583.0 437080.0 438422.7 52230.5 52428.0 52463.5 52529.0 52531.0 1355.7 1390.7 1411.5 1412.2 1414.4 25392.5 26076.3 26285.0 26979.9 27249.3 45242.3 45618.3 45729.8 45810.3 45813.8 331658.0 333017.0 333907.0 336086.0 336150.0 25182.0 25540.2 25586.9 25696.6 25779.5 41228.0 41373.4 41643.4 41649.4 42325.0 34498.5 34641.8 34890.8 34971.7 35016.8 34286.5 34355.1 34489.3 34490.5 34754.7 34355.0 34767.8 35234.3 35318.8 35555.1 28725.0 30833.0 31093.8 31218.4 31342.2 23135.9 23405.2 23413.3 23719.2 23729.2 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | [#2] jquery.html Cycles:180 Average:29822.09 Median:2968.42 stddev:82945.10 (2794.3%) stddev-sans-first:83148.30 21:47:39 INFO - PROCESS | 24204 | Values: 372.3 412.3 428.8 430.5 432.1 12813.4 14302.1 19076.0 19566.6 19646.1 21127.4 21421.0 21543.4 21672.0 21691.8 5754.8 5861.8 5997.7 6080.0 6146.3 3588.6 6134.0 6165.4 6175.9 6183.0 3730.7 3961.6 4084.1 6665.2 6702.3 6517.1 6783.6 6785.7 6788.8 6794.8 6003.1 6022.0 6036.4 6065.1 6069.3 13872.9 13965.8 14196.9 14203.6 14461.8 2941.1 2944.6 2992.2 2997.3 3002.6 10477.3 10538.3 11073.1 11192.8 11987.0 1743.1 1753.0 1767.5 1770.6 1820.1 88588.0 89149.6 89741.0 89815.0 90962.5 11827.0 11877.8 11985.2 12043.8 12285.9 17099.7 17199.0 17225.2 17239.2 17257.7 8206.5 8520.6 8550.2 8564.3 8610.6 7722.5 7723.0 7937.1 8006.0 8016.6 5078.3 5140.8 5166.7 5195.3 5213.6 2110.5 2159.2 2164.5 2196.6 2199.2 814.1 836.4 849.4 851.6 861.9 18786.0 19292.1 19376.3 19394.8 19938.1 840.0 842.4 846.1 846.5 846.7 9946.3 10650.0 10766.3 10791.9 10849.4 421413.0 424162.0 428108.0 586118.0 601274.0 104530.5 108134.5 110373.4 111775.0 113118.4 1040.6 1045.9 1087.9 1097.7 1101.5 17206.4 17668.2 17952.4 18144.6 18282.7 41700.2 42054.6 42725.0 43222.0 43404.3 60179.4 60305.5 61145.4 61454.7 62278.3 8477.7 8538.9 8898.2 8950.3 9027.6 12738.3 13107.1 13174.3 13381.7 13631.7 11447.6 11467.4 11476.9 11674.9 11963.4 11603.6 11680.1 11733.4 11784.5 11839.8 10818.9 11307.8 11484.4 11544.8 11700.0 8549.0 8705.0 8731.0 8793.3 8824.7 10262.8 10785.7 11101.9 11498.5 12426.6 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | [#3] mootools.html Cycles:180 Average:5281.75 Median:5195.81 stddev:2990.28 (57.6%) stddev-sans-first:2984.35 21:47:39 INFO - PROCESS | 24204 | Values: 1388.3 1391.6 1392.5 1401.3 1409.2 7744.2 8240.8 8250.6 8263.5 8269.2 7776.7 8177.8 8207.3 8372.7 8384.4 2186.8 2207.0 2211.8 2214.1 2214.2 1913.2 2170.7 2202.3 2203.7 2209.3 1912.0 2003.2 2072.8 2075.2 2076.4 2114.9 2117.1 2134.4 2135.4 2141.8 2098.4 2173.6 2173.8 2181.0 2182.3 8322.4 8360.7 8366.2 8368.4 8385.2 4787.2 4878.5 4892.6 4896.5 4897.1 3027.5 3140.7 3214.8 3218.7 3233.4 1282.2 1282.6 1308.9 1331.2 1380.4 11108.3 11280.7 11309.2 11344.1 11361.8 3918.7 4104.3 4161.7 4172.4 4221.3 8954.5 8965.3 8981.4 8987.4 9005.3 4889.4 4891.9 4892.6 4894.8 4896.4 4370.7 4385.6 4497.4 4505.5 4548.0 3391.2 3429.1 3467.5 3491.3 3522.9 1018.7 1024.2 1038.7 1040.6 1042.7 722.3 729.8 744.2 751.6 776.8 5148.3 5243.4 5335.5 5445.8 5546.3 607.2 621.1 622.5 626.9 761.7 3510.2 3560.8 3742.6 3803.6 3806.4 9412.5 9965.6 9969.3 9978.1 9983.2 9672.6 9683.3 9856.7 9873.3 9897.3 7657.7 7676.5 7739.6 7740.2 7744.7 5246.2 5272.5 5285.4 5367.4 5472.3 8648.3 8760.5 8801.7 8818.3 8888.0 8930.9 9061.8 9103.5 9160.6 9174.4 7095.6 7178.1 7208.7 7218.7 7221.9 7693.3 7781.3 7832.9 7861.0 7872.7 7000.9 7008.4 7019.0 7118.1 7178.4 6863.9 6874.9 6888.2 7048.8 7098.5 5892.5 5986.4 6036.0 6041.5 6044.8 4758.4 4813.8 4836.9 4839.0 5021.4 4860.3 4869.9 4945.7 4976.2 5094.1 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | [#4] prototype.html Cycles:180 Average:4636.01 Median:5146.79 stddev:1306.95 (25.4%) stddev-sans-first:1271.00 21:47:39 INFO - PROCESS | 24204 | Values: 381.2 403.7 407.6 408.5 411.3 5050.7 5147.0 5225.5 5302.5 5345.9 5077.8 5201.3 5217.8 5222.2 5226.4 4150.0 4155.3 4157.3 4169.5 4176.5 3837.8 4066.4 4182.8 4222.9 4225.9 4081.3 4123.8 4131.9 4146.0 4157.0 4119.9 4120.2 4142.7 4156.4 4166.7 3733.7 3767.7 3789.7 3793.7 3807.5 4872.0 5055.2 5110.2 5111.6 5129.5 4866.2 4870.0 4878.1 4885.4 4893.2 5315.6 5451.1 5485.1 5488.2 5523.0 4149.8 4177.1 4180.9 4189.2 4213.3 5663.2 5672.7 5694.7 5697.3 5726.2 4310.3 4326.5 4332.5 4343.1 4357.9 4167.7 4320.9 4335.6 4349.5 4366.1 4734.5 4803.3 4809.9 4856.2 4859.4 5485.8 5487.5 5514.9 5518.5 5528.4 1388.2 1457.4 1457.6 1460.4 1460.8 1035.2 1067.2 1070.3 1077.2 1086.3 5092.1 5111.9 5120.9 5129.4 5150.2 5443.3 5448.9 5460.0 5461.8 5507.6 4814.9 4922.5 5083.8 5096.8 5146.6 2871.6 2871.7 2877.2 2888.3 2896.2 5698.4 5713.4 5717.5 5720.7 5728.9 5379.3 5466.9 5541.0 5596.3 5602.2 5610.5 5699.6 5702.9 5705.6 5723.1 5281.5 5401.5 5403.9 5427.3 5436.8 5571.3 5585.2 5585.4 5594.3 5604.5 5827.6 5856.4 5890.7 5903.4 5914.9 3949.8 4071.7 4077.9 4082.8 4089.1 5551.9 5557.6 5563.8 5570.5 5609.8 5433.7 5442.6 5453.3 5464.3 5466.2 5202.6 5429.6 5467.5 5473.2 5494.5 5456.2 5460.3 5475.4 5480.9 5497.7 5297.9 5486.3 5510.6 5526.7 5537.6 5320.7 5346.5 5348.7 5355.7 5362.9 21:47:39 INFO - PROCESS | 24204 | 21:47:39 INFO - PROCESS | 24204 | [#5] yui.html Cycles:180 Average:16631.74 Median:26195.39 stddev:21459.12 (81.9%) stddev-sans-first:21486.01 21:47:39 INFO - PROCESS | 24204 | Values: 710.1 743.8 805.6 814.4 828.9 15114.2 16192.0 16232.0 16241.6 16370.2 14927.3 15172.0 15180.8 15183.8 15207.6 773.9 800.6 819.5 823.4 830.0 829.8 830.8 831.4 831.8 832.6 857.0 862.1 862.3 862.3 862.4 847.3 848.9 854.3 855.5 856.2 623.8 624.0 624.6 625.3 625.5 14598.5 14604.6 14605.8 14857.5 14950.1 12996.7 13647.4 13710.0 13713.4 13735.3 11522.7 12121.1 12132.8 12219.5 12706.7 3672.3 3674.2 3811.1 3819.8 3890.2 71347.6 71373.0 72473.6 73406.0 74218.6 10148.1 10295.4 10302.9 10447.9 10760.4 17896.6 17913.1 18015.3 18024.5 18026.1 7405.7 7477.5 7669.9 7715.8 7725.2 4186.9 4217.7 4293.5 4298.3 4433.3 3294.3 3361.5 3380.1 3384.2 3388.7 576.3 580.2 582.7 589.4 589.5 683.4 739.9 745.1 746.7 757.4 9357.6 9702.2 9870.6 9958.5 10090.8 705.7 720.9 727.6 732.5 733.9 4532.5 4849.9 4930.4 4933.3 4935.0 73986.6 75358.6 76238.0 78251.0 78496.0 65712.0 67697.0 67880.0 68159.3 68575.7 60462.7 60565.7 60779.4 60787.7 60822.7 9255.7 9455.7 9558.2 9575.4 9599.8 41735.2 42308.0 42497.2 42604.8 43127.4 49371.8 51038.0 51547.2 51642.5 51814.5 10945.9 11030.9 11187.1 11319.2 11874.6 11399.7 11652.5 11883.7 12163.0 12465.8 11709.5 11861.6 11914.9 11964.3 12023.9 11593.9 11715.8 12126.8 12452.8 12466.8 11490.8 11985.7 12018.8 12043.0 12154.2 8877.7 9387.9 9426.7 9583.9 9826.8 8272.9 9695.2 9729.8 9794.4 9905.5 21:47:39 INFO - PROCESS | 24204 | -------- Summary: end -------- 21:47:39 INFO - PROCESS | 24204 | 21:47:40 INFO - TEST-INFO | 24204: exit 0 21:47:40 INFO - TEST-OK | dromaeo_css | took 1116570ms 21:47:40 INFO - TEST-START | kraken 21:47:40 INFO - operating with platform_type : linux_ 21:47:40 INFO - Initialising browser for kraken test... 21:47:40 INFO - TEST-INFO | started process 24524 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoYiph8/profile http://localhost:55175/getInfo.html) 21:47:43 INFO - PROCESS | 24524 | Iterating over profile /tmp/tmpoYiph8/profile 21:47:44 INFO - PROCESS | 24524 | __metrics Screen width/height:1600/1200 21:47:44 INFO - PROCESS | 24524 | colorDepth:24 21:47:44 INFO - PROCESS | 24524 | Browser inner width/height: 1024/697 21:47:44 INFO - PROCESS | 24524 | __metrics 21:47:44 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:44 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/pluginreg.dat = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:44 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:44 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/thumbnails = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/permissions.sqlite = Mon Feb 20 2017 21:47:41 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/bookmarkbackups = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/places.sqlite-shm = Mon Feb 20 2017 21:47:42 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/webappsstore.sqlite = Mon Feb 20 2017 21:47:44 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/startupCache = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/user.js = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/safebrowsing = Mon Feb 20 2017 21:47:44 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/localstore.rdf = Mon Feb 20 2017 21:28:21 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/crashes = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/frequencyCap.json = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/cookies.sqlite = Mon Feb 20 2017 21:47:44 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/gmp = Mon Feb 20 2017 21:47:41 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/extensions.ini = Mon Feb 20 2017 21:47:41 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/.parentlock = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/cert8.db = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/addons.json = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/minidumps = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/places.sqlite = Mon Feb 20 2017 21:47:45 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/prefs.js = Mon Feb 20 2017 21:28:21 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/extensions.json = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/sessionCheckpoints.json = Mon Feb 20 2017 21:47:44 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/directoryLinks.json = Mon Feb 20 2017 21:47:43 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/cache2 = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/places.sqlite-wal = Mon Feb 20 2017 21:47:45 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/blocklist.xml = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | Stat failure 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/key3.db = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/compatibility.ini = Mon Feb 20 2017 21:47:41 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/content-prefs.sqlite = Mon Feb 20 2017 21:47:42 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/secmod.db = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | Stat failure 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/mimeTypes.rdf = Mon Feb 20 2017 21:47:44 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | No birth date. Using mtime. 21:47:45 INFO - PROCESS | 24524 | Using date: /tmp/tmpoYiph8/profile/extensions = Mon Feb 20 2017 21:47:40 GMT-0800 (PST) 21:47:45 INFO - PROCESS | 24524 | 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" 21:47:45 INFO - PROCESS | 24524 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:47:46 INFO - TEST-INFO | 24524: exit 0 21:47:46 INFO - Browser initialized. 21:47:46 INFO - Running cycle 1/1 for kraken test... 21:47:46 INFO - TEST-INFO | started process 24616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoYiph8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:47:58 INFO - PROCESS | 24616 | RSS: Main: 142532608 21:47:58 INFO - PROCESS | 24616 | 21:48:01 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55175/tests/kraken/driver.html?testName=audio-beat-detection) 21:48:02 INFO - PROCESS | 24616 | RSS: Main: 198078464 21:48:02 INFO - PROCESS | 24616 | 21:48:04 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55175/tests/kraken/driver.html?testName=audio-dft) 21:48:05 INFO - PROCESS | 24616 | RSS: Main: 177348608 21:48:05 INFO - PROCESS | 24616 | 21:48:08 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55175/tests/kraken/driver.html?testName=audio-fft) 21:48:09 INFO - PROCESS | 24616 | RSS: Main: 259559424 21:48:09 INFO - PROCESS | 24616 | 21:48:11 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55175/tests/kraken/driver.html?testName=audio-oscillator) 21:48:11 INFO - PROCESS | 24616 | RSS: Main: 170573824 21:48:11 INFO - PROCESS | 24616 | 21:48:13 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55175/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:48:14 INFO - PROCESS | 24616 | RSS: Main: 173813760 21:48:14 INFO - PROCESS | 24616 | 21:48:17 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55175/tests/kraken/driver.html?testName=imaging-darkroom) 21:48:18 INFO - PROCESS | 24616 | RSS: Main: 213790720 21:48:18 INFO - PROCESS | 24616 | 21:48:21 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55175/tests/kraken/driver.html?testName=imaging-desaturate) 21:48:21 INFO - PROCESS | 24616 | RSS: Main: 213692416 21:48:21 INFO - PROCESS | 24616 | 21:48:24 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55175/tests/kraken/driver.html?testName=json-parse-financial) 21:48:25 INFO - PROCESS | 24616 | RSS: Main: 212652032 21:48:25 INFO - PROCESS | 24616 | 21:48:26 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55175/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:48:27 INFO - PROCESS | 24616 | RSS: Main: 174325760 21:48:27 INFO - PROCESS | 24616 | 21:48:29 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:48:29 INFO - PROCESS | 24616 | RSS: Main: 182091776 21:48:29 INFO - PROCESS | 24616 | 21:48:31 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:48:32 INFO - PROCESS | 24616 | RSS: Main: 190296064 21:48:32 INFO - PROCESS | 24616 | 21:48:35 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:48:35 INFO - PROCESS | 24616 | RSS: Main: 199614464 21:48:35 INFO - PROCESS | 24616 | 21:48:38 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:48:39 INFO - PROCESS | 24616 | RSS: Main: 196657152 21:48:39 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | Cycle 1(1): loaded http://localhost:55175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55175/tests/kraken/driver.html?testName=ai-astar) 21:48:41 INFO - PROCESS | 24616 | RSS: Main: 248246272 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | __start_tp_report 21:48:41 INFO - PROCESS | 24616 | _x_x_mozilla_page_load 21:48:41 INFO - PROCESS | 24616 | _x_x_mozilla_page_load_details 21:48:41 INFO - PROCESS | 24616 | |i|pagename|runs| 21:48:41 INFO - PROCESS | 24616 | |0;ai-astar;112;115;113;114;113;114;116;114;114;114 21:48:41 INFO - PROCESS | 24616 | |1;audio-beat-detection;142;126;120;173;120;121;121;124;143;121 21:48:41 INFO - PROCESS | 24616 | |2;audio-dft;160;160;162;162;161;159;161;161;161;162 21:48:41 INFO - PROCESS | 24616 | |3;audio-fft;74;75;76;104;76;76;77;75;95;76 21:48:41 INFO - PROCESS | 24616 | |4;audio-oscillator;83;84;120;82;83;83;97;82;82;100 21:48:41 INFO - PROCESS | 24616 | |5;imaging-gaussian-blur;114;113;113;114;114;113;113;113;113;113 21:48:41 INFO - PROCESS | 24616 | |6;imaging-darkroom;147;147;147;146;147;147;147;146;146;147 21:48:41 INFO - PROCESS | 24616 | |7;imaging-desaturate;96;96;97;95;96;97;96;95;96;95 21:48:41 INFO - PROCESS | 24616 | |8;json-parse-financial;57;54;55;55;57;55;55;57;58;56 21:48:41 INFO - PROCESS | 24616 | |9;json-stringify-tinderbox;63;64;63;63;63;63;63;63;63;63 21:48:41 INFO - PROCESS | 24616 | |10;stanford-crypto-aes;84;84;84;84;84;84;84;84;83;83 21:48:41 INFO - PROCESS | 24616 | |11;stanford-crypto-ccm;130;147;130;137;129;127;128;129;175;138 21:48:41 INFO - PROCESS | 24616 | |12;stanford-crypto-pbkdf2;173;173;169;169;171;170;172;171;171;171 21:48:41 INFO - PROCESS | 24616 | |13;stanford-crypto-sha256-iterative;66;64;63;63;67;63;64;63;63;63 21:48:41 INFO - PROCESS | 24616 | __end_tp_report 21:48:41 INFO - PROCESS | 24616 | __start_cc_report 21:48:41 INFO - PROCESS | 24616 | _x_x_mozilla_cycle_collect,1693 21:48:41 INFO - PROCESS | 24616 | __end_cc_report 21:48:41 INFO - PROCESS | 24616 | __startTimestamp1487656121308__endTimestamp 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | ------- Summary: start ------- 21:48:41 INFO - PROCESS | 24616 | Number of tests: 14 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.10 (1.0%) stddev-sans-first:0.93 21:48:41 INFO - PROCESS | 24616 | Values: 112.0 115.0 113.0 114.0 113.0 114.0 116.0 114.0 114.0 114.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:125.00 stddev:17.14 (13.7%) stddev-sans-first:17.72 21:48:41 INFO - PROCESS | 24616 | Values: 142.0 126.0 120.0 173.0 120.0 121.0 121.0 124.0 143.0 121.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 21:48:41 INFO - PROCESS | 24616 | Values: 160.0 160.0 162.0 162.0 161.0 159.0 161.0 161.0 161.0 162.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.00 stddev:10.32 (13.6%) stddev-sans-first:10.68 21:48:41 INFO - PROCESS | 24616 | Values: 74.0 75.0 76.0 104.0 76.0 76.0 77.0 75.0 95.0 76.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:83.00 stddev:12.57 (15.1%) stddev-sans-first:13.11 21:48:41 INFO - PROCESS | 24616 | Values: 83.0 84.0 120.0 82.0 83.0 83.0 97.0 82.0 82.0 100.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 21:48:41 INFO - PROCESS | 24616 | Values: 114.0 113.0 113.0 114.0 114.0 113.0 113.0 113.0 113.0 113.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 21:48:41 INFO - PROCESS | 24616 | Values: 147.0 147.0 147.0 146.0 147.0 147.0 147.0 146.0 146.0 147.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 21:48:41 INFO - PROCESS | 24616 | Values: 96.0 96.0 97.0 95.0 96.0 97.0 96.0 95.0 96.0 95.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.29 (2.3%) stddev-sans-first:1.30 21:48:41 INFO - PROCESS | 24616 | Values: 57.0 54.0 55.0 55.0 57.0 55.0 55.0 57.0 58.0 56.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 21:48:41 INFO - PROCESS | 24616 | Values: 63.0 64.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 21:48:41 INFO - PROCESS | 24616 | Values: 84.0 84.0 84.0 84.0 84.0 84.0 84.0 84.0 83.0 83.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:133.50 stddev:14.73 (11.0%) stddev-sans-first:15.40 21:48:41 INFO - PROCESS | 24616 | Values: 130.0 147.0 130.0 137.0 129.0 127.0 128.0 129.0 175.0 138.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:171.00 stddev:1.41 (0.8%) stddev-sans-first:1.30 21:48:41 INFO - PROCESS | 24616 | Values: 173.0 173.0 169.0 169.0 171.0 170.0 172.0 171.0 171.0 171.0 21:48:41 INFO - PROCESS | 24616 | 21:48:41 INFO - PROCESS | 24616 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:63.50 stddev:1.45 (2.3%) stddev-sans-first:1.32 21:48:41 INFO - PROCESS | 24616 | Values: 66.0 64.0 63.0 63.0 67.0 63.0 64.0 63.0 63.0 63.0 21:48:41 INFO - PROCESS | 24616 | -------- Summary: end -------- 21:48:41 INFO - PROCESS | 24616 | 21:48:42 INFO - TEST-INFO | 24616: exit 0 21:48:42 INFO - TEST-OK | kraken | took 61894ms 21:48:42 INFO - SUITE-END | took 1178s 21:48:42 INFO - Completed test suite (00:19:38) 21:48:42 INFO - javascript benchmark 21:48:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6954.765730965494, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3081.2913030003097, 3105.3013072058784, 3116.47020006599, 3152.6689697581737, 3173.714365707086, 5096.872578185543, 5103.081445339837, 5158.587313014959, 5174.172132458807, 5202.21966704994, 5178.948210517894, 5190.610704197182, 5272.103742363798, 5280.2343660169345, 5316.255724198615, 4698.718076915387, 4704.905901881961, 4786.545278198566, 4787.904241915159, 4794.400699912511, 4794.0, 4813.0, 4823.831165909194, 4825.855224343275, 4828.130936431441, 4540.931886021729, 4562.110388474249, 4565.223911934946, 4686.5782079613, 4782.019685964387, 4723.456802467692, 4760.785764640599, 4775.188218002948, 4808.422989241279, 4809.1824389853455, 4720.504347043546, 4735.076660051292, 4763.237881938908, 4768.332433459318, 4771.522847715235, 5005.324281222025, 5306.416294207634, 5314.867128321823, 5327.413984461706, 5379.381371142291, 5421.945780542223, 5484.369297530795, 5488.451154884519, 5490.972545137249, 5502.642328248651, 5551.8889622207735, 5552.86117847053, 5558.499735023827, 5560.77756889724, 5564.360098588633, 5124.564412024983, 5185.429602743658, 5188.066148093341, 5193.143131383317, 5276.34045744282, 5783.479486846204, 5824.708764561755, 5871.7651293947765, 5875.50058245054, 5881.029630110984, 5281.445448227958, 5313.867153321198, 5324.520793128637, 5614.382417934024, 5661.46216109469, 5459.863503412446, 5466.070767969455, 5468.644538105011, 5477.45225477457, 5479.369872464637, 5547.972260138754, 5552.250446189793, 5581.302337207849, 5623.550115990711, 5632.04255276604, 5464.480874317013, 5500.7524661390335, 5614.803481878114, 5619.662820230799, 5621.662700237999, 4331.740095594275, 4341.522432532419, 4377.0, 4393.450818647669, 4414.381986521889, 3945.033466800652, 3952.209558088394, 3958.050067983663, 3988.581198974124, 3989.7406668567032, 5410.702411367412, 5427.0, 5428.375736790241, 5430.2679447655455, 5478.726063696799, 5487.533559647395, 5541.224228607998, 5546.057170280981, 5599.860003499945, 5752.0, 5222.92165617516, 5272.393674727378, 5295.841124766263, 5299.205119232069, 5328.946710532922, 4810.855674329776, 4840.201366774443, 4874.049560335771, 4883.096627123993, 4890.731009794425, 5906.527477801766, 5906.763729450774, 5911.586188966817, 5914.0, 5960.225170727777, 5348.171033489815, 5354.9464505354445, 5362.168863826113, 5601.355844077983, 5657.688827114548, 5656.406077361818, 5659.0, 5859.765609375577, 5921.733521991607, 5957.16599676037, 5634.83095507119, 5666.631668941505, 5676.035074037506, 5680.091185410314, 5680.573956953328, 5662.122371032497, 5719.914201287067, 5722.141678748221, 5726.284214473191, 5727.055035919193, 5360.544353729977, 5581.0, 5581.74882130297, 5584.329880414376, 5589.133684278943, 5352.036633405869, 5462.317210348707, 5528.502434780728, 5540.556755459473, 5554.222408862682, 5487.478689524489, 5487.6981766003255, 5491.643043202179, 5498.257735205696, 5501.78960628677, 5480.6437581557075, 5527.723613819373, 5531.0873705839695, 5533.806316778893, 5570.080936645408, 5255.185446255837, 5307.761150748149, 5308.628396012242, 5368.060589396762, 5382.569394448356], "value": 5178.210433388452, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.5747232103603, 585.5666806563145, 605.3401792046672, 611.0223642172523, 613.4724137241972, 29860.701392986062, 30479.847600761994, 33483.832580837094, 42705.57294427055, 43887.34168987468, 46737.766311168394, 47030.76484617581, 47085.05829883409, 47254.527454725445, 47354.289685654745, 9162.633494660222, 9394.530273486333, 9395.0, 9397.342186046979, 9398.34211605188, 7395.0, 7577.659005344773, 9106.089391060907, 9217.0, 10526.894731052706, 10539.84190237147, 11124.221304508688, 11131.554737810478, 11131.554737810478, 11132.61035863745, 10968.23222374434, 11093.944530277297, 11104.389258590774, 11111.055560277386, 11117.277376970553, 10648.733781655443, 10682.198835087322, 10686.51910664022, 10686.51910664022, 10736.892631073746, 44227.33658995118, 44421.55578444206, 44561.33158002632, 44600.0, 44642.55357446417, 3890.9027274318087, 3972.245273398045, 3998.900027499307, 3999.4000899864964, 4107.075907920724, 31189.220269493217, 31688.366232675216, 31874.203144921332, 32117.51823722646, 33390.83304583486, 3900.7659540427667, 4021.155557332934, 4057.736247143926, 4091.0, 4166.416701661769, 326100.3694981534, 328923.0, 329376.0, 329720.0, 329898.0, 60482.39517604768, 60726.4818379544, 60984.39015609876, 60985.695071525246, 61039.69480152571, 63164.0, 63177.36822631715, 63270.091238595174, 63292.36707632957, 63401.0, 15996.360145594046, 16339.918300408659, 16340.75488867668, 16341.428050018192, 16348.10085445312, 11778.646640600795, 11782.823257651142, 11784.116191285688, 11787.941060294643, 11792.764144717057, 14747.705045899236, 15013.24933753308, 16610.6677866442, 16905.23926423314, 16914.83085169157, 6197.938020619736, 6907.0, 7014.614196219155, 7020.05229294049, 7035.788926332219, 899.3299991506238, 901.2789768185426, 935.756703257158, 939.4832841936908, 957.760559860035, 27424.45151096967, 28158.0, 28882.133535993955, 30271.243218919262, 30877.0, 1051.321897376188, 1063.6915294564644, 1072.951717172729, 1075.1828610256262, 1109.916756243285, 13697.178169309675, 14452.053768113821, 14683.632909177357, 14842.777358339634, 15168.696626067638, 431967.0, 436021.0, 436583.0, 437080.0, 438422.69968705846, 52230.47769522332, 52428.0, 52463.475365246624, 52529.0, 52530.999999999236, 1355.6746380868522, 1390.728807882453, 1411.498917884157, 1412.2373918084324, 1414.405949501212, 25392.492150157264, 26076.34809129787, 26285.0, 26979.86510067437, 27249.31876703098, 45242.32136517889, 45618.315725263485, 45729.771351143034, 45810.31284530801, 45813.77093114513, 331658.0, 333017.0, 333906.96558206587, 336086.0, 336150.0, 25182.0, 25540.23379298624, 25586.8720656403, 25696.614550782127, 25779.484410312063, 41227.952468279786, 41373.37939930843, 41643.37534937039, 41649.37525937053, 42325.0, 34498.482522761675, 34641.82679086588, 34890.82554587211, 34971.65028349684, 35016.82491587628, 34286.48570271498, 34355.14112147217, 34489.310213795085, 34490.482642759875, 34754.652453476156, 34355.0, 34767.82616086904, 35234.29531409306, 35318.82340588281, 35555.11112222215, 28725.0, 30833.0, 31093.8445307772, 31218.409882862852, 31342.216444588157, 23135.88432057829, 23405.180818671266, 23413.297601071994, 23719.1698290559, 23729.16947906815], "value": 19037.63584104839, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.33352299384103, 412.3279055140123, 428.7513242319455, 430.4554738256107, 432.14866712576213, 12813.423395947182, 14302.141871487705, 19076.0, 19566.608667826637, 19646.115924783382, 21127.366179014614, 21421.0, 21543.353699389045, 21672.0, 21691.783082169175, 5754.769809207627, 5861.824145275648, 5997.730102145414, 6079.969600151993, 6146.29317628474, 3588.6411358864034, 6133.987891997837, 6165.414285642857, 6175.907361389561, 6182.969085154568, 3730.7201959852982, 3961.564227934925, 4084.101497670508, 6665.166854143232, 6702.329767023308, 6517.120188774502, 6783.6269005204695, 6785.660716964132, 6788.8302792430095, 6794.796156115324, 6003.099535069731, 6022.0, 6036.396360363972, 6065.090236464522, 6069.271687397496, 13872.93063534686, 13965.79051314231, 14196.858031419657, 14203.57389278313, 14461.78307325391, 2941.132365952028, 2944.6319210098736, 2992.2070651277427, 2997.3405850712807, 3002.6246719160104, 10477.266591338534, 10538.315009524356, 11073.114150867912, 11192.776144477146, 11987.041036717043, 1743.1119763214879, 1752.9649407011789, 1767.4962635648778, 1770.6281680847037, 1820.1263393571028, 88588.0, 89149.55425222898, 89741.0, 89815.0, 90962.5451872743, 11827.0, 11877.762444751057, 11985.220960637706, 12043.819342709867, 12285.938570307091, 17099.658006839793, 17199.0, 17225.22486488086, 17239.22423490946, 17257.65484690299, 8206.507609543447, 8520.57397130141, 8550.187732165434, 8564.3148548116, 8610.64184967863, 7722.459427839997, 7722.961385193038, 7937.126916039231, 8006.0, 8016.639251233709, 5078.289039534468, 5140.7943682253035, 5166.741662916839, 5195.324607801037, 5213.582913366921, 2110.4512826665177, 2159.157928407922, 2164.5238047629496, 2196.6375548034716, 2199.2412617646887, 814.1329484099415, 836.3810780022861, 849.3502470609989, 851.5657014922434, 861.9138086191331, 18786.0, 19292.1318540666, 19376.321828735927, 19394.806051939584, 19938.1027853744, 839.9748007559783, 842.4229402859061, 846.0725888425992, 846.5428668519054, 846.6655671009917, 9946.254030947708, 10650.0, 10766.300190487593, 10791.892081079088, 10849.403282819521, 421413.0, 424162.0, 428108.0, 586118.0, 601274.0, 104530.47734761277, 108134.45932770286, 110373.44813275883, 111775.0, 113118.43440782743, 1040.5889673578895, 1045.9320144190604, 1087.8640169978753, 1097.676185525256, 1101.5098281264761, 17206.397776077778, 17668.204930778404, 17952.371666991592, 18144.637107257517, 18282.72575911389, 41700.1659966805, 42054.57945420629, 42725.0, 43222.0, 43404.348934765374, 60179.39820601738, 60305.52595675954, 61145.38854611575, 61454.69272653787, 62278.32912531488, 8477.703280385156, 8538.91461085406, 8898.243649289883, 8950.328725345755, 9027.55559110539, 12738.299393533445, 13107.148035378052, 13174.275414852274, 13381.66545836362, 13631.65920851947, 11447.599334023269, 11467.369294688539, 11476.942615286871, 11674.883251167379, 11963.401829908644, 11603.5938742147, 11680.123990700902, 11733.35466549348, 11784.528618855493, 11839.76320473603, 10818.891811081789, 11307.773844523228, 11484.368359740294, 11544.769104618028, 11700.0, 8549.0, 8705.042445331135, 8731.0, 8793.252573531321, 8824.735257942271, 10262.846057309296, 10785.730356741144, 11101.888981110085, 11498.54005839757, 12426.62720118398], "value": 9332.581305777754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.2503448138007, 1391.5825252424277, 1392.470861072792, 1401.306353355089, 1409.18971591335, 7744.1868603796565, 8240.7939801505, 8250.628721707522, 8263.462874913126, 8269.173082691736, 7776.650050747716, 8177.79555511111, 8207.261346478816, 8372.665093396272, 8384.413091083627, 2186.7813218678166, 2206.9779302206935, 2211.7788221177916, 2214.1032881682945, 2214.180753121347, 1913.1773337464886, 2170.7395112586432, 2202.273249827553, 2203.6914831923464, 2209.3151123151874, 1911.9904400477978, 2003.188708573023, 2072.8134467897885, 2075.2217918280644, 2076.4393613724287, 2114.8625339352893, 2117.12139462123, 2134.3810295014428, 2135.4020874155262, 2141.7858214178614, 2098.380977611608, 2173.5870184665023, 2173.7608863025052, 2181.040342249421, 2182.2907555044676, 8322.375821813328, 8360.665573377057, 8366.205210504992, 8368.414211005233, 8385.161483851627, 4787.186178349689, 4878.4633690294395, 4892.559669629751, 4896.510348965111, 4897.118518666639, 3027.544720595216, 3140.7173354398215, 3214.758893083028, 3218.742500599946, 3233.3533293341193, 1282.2434763489493, 1282.634449181988, 1308.9018323625671, 1331.2079312808862, 1380.3581334679423, 11108.333499990027, 11280.717982050432, 11309.208355415125, 11344.092472602171, 11361.772764544745, 3918.74528155669, 4104.343305071234, 4161.687873409506, 4172.3532852407925, 4221.324588065956, 8954.507502087448, 8965.282777377794, 8981.371304008788, 8987.370884038055, 9005.279577633773, 4889.3888263967, 4891.926621100686, 4892.633052521004, 4894.828680996148, 4896.4124305082905, 4370.7159034662645, 4385.583369579885, 4497.437820272466, 4505.481869584974, 4547.977260113678, 3391.1522119470133, 3429.0741499794917, 3467.497212904115, 3491.3366460372954, 3522.859085636546, 1018.7453136715822, 1024.2164743970868, 1038.6987773545738, 1040.552562398161, 1042.6663467690419, 722.2741145149091, 729.824842037907, 744.192551082082, 751.6091632351256, 776.7902666280073, 5148.253503242009, 5243.370795504564, 5335.466453354634, 5445.782168713286, 5546.306711661043, 607.1803065861052, 621.0621960839165, 622.5393209025289, 626.9279032911273, 761.7410080572632, 3510.227749895019, 3560.786352818839, 3742.55089389275, 3803.6196380361744, 3806.410006449005, 9412.529373531295, 9965.601375945025, 9969.252306077073, 9978.052085051908, 9983.201343892471, 9672.613095476243, 9683.322167448352, 9856.704298871045, 9873.259505537113, 9897.307188496736, 7657.7319793808065, 7676.53940763556, 7739.639409979897, 7740.187280335596, 7744.690212391554, 5246.186841039645, 5272.525472707474, 5285.365756109291, 5367.409584945574, 5472.26124473207, 8648.264897483532, 8760.474371537728, 8801.691940782042, 8818.250448711931, 8888.0, 8930.910690893268, 9061.77345566366, 9103.49930753815, 9160.633574656938, 9174.403663761834, 7095.580883823152, 7178.138623365229, 7208.675609597476, 7218.747343842941, 7221.927780722336, 7693.269139431969, 7781.26078022587, 7832.921670783448, 7861.0, 7872.724454644289, 7000.859982800213, 7008.369246767816, 7018.964905175646, 7118.145822501333, 7178.425725941807, 6863.931360686329, 6874.9312506876295, 6888.242293347828, 7048.823779405556, 7098.538594991309, 5892.499137573182, 5986.4013598639785, 6036.0, 6041.516678665608, 6044.818655440344, 4758.381410416624, 4813.78337974799, 4836.854894353175, 4838.975805120952, 5021.372328458942, 4860.3195552621955, 4869.9026019480125, 4945.703257804544, 4976.158821236166, 5094.057599343985], "value": 4199.525301553225, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.2394273424517, 403.74967520137517, 407.5944435286889, 408.51590864825187, 411.29873565570705, 5050.671706339094, 5146.974265128679, 5225.503577160169, 5302.469753024696, 5345.92609249681, 5077.781332480196, 5201.297824793652, 5217.843464696056, 5222.216667499877, 5226.425093239741, 4150.0, 4155.252054630166, 4157.314043182879, 4169.457970463851, 4176.4570605821355, 3837.750546214501, 4066.3900414937702, 4182.811773470186, 4222.9155416891645, 4225.915481690365, 4081.265372232997, 4123.793810309473, 4131.925699318183, 4145.97927010366, 4156.97921510392, 4119.876403707894, 4120.21715873986, 4142.668586513087, 4156.376543518466, 4166.666666666638, 3733.6826369758605, 3767.6640482900775, 3789.6968242540806, 3793.6775374093236, 3807.505024346845, 4871.951280487186, 5055.165897626924, 5110.156824124052, 5111.565516931066, 5129.461406552333, 4866.221404575286, 4869.9513004870205, 4878.146304404304, 4885.389326334208, 4893.1926232171645, 5315.6013299002725, 5451.127819548853, 5485.067538518421, 5488.204210389471, 5523.0, 4149.8173020688955, 4177.143685544456, 4180.853670121531, 4189.183109293689, 4213.346931225665, 5663.207150998864, 5672.744726487318, 5694.658320500783, 5697.287839020122, 5726.198332233491, 4310.28880234764, 4326.459192600925, 4332.501762297313, 4343.087951530214, 4357.934630980538, 4167.729097608646, 4320.870373888788, 4335.631471324973, 4349.478062632505, 4366.148601022834, 4734.455537613218, 4803.303520989437, 4809.855704328876, 4856.22747697088, 4859.392575928009, 5485.807996720176, 5487.478689524489, 5514.944850551524, 5518.53092487135, 5528.419515950848, 1388.1532265318149, 1457.3806132393902, 1457.5845883923032, 1460.350144185827, 1460.8094403061684, 1035.2080658296409, 1067.1622776120864, 1070.341739829999, 1077.224398433127, 1086.2830531848942, 5092.115917110693, 5111.897762044739, 5120.948790512121, 5129.358830146231, 5150.175971844487, 5443.265159203536, 5448.891022179613, 5459.972700136474, 5461.781528738806, 5507.614466987273, 4814.927776083362, 4922.532359425849, 5083.847484575469, 5096.763555142511, 5146.614003949719, 2871.6410448693914, 2871.655401351851, 2877.1800036989357, 2888.277930517371, 2896.218021134282, 5698.430156984268, 5713.428657134254, 5717.456841600041, 5720.68536230503, 5728.856778580569, 5379.252431355649, 5466.922569423594, 5541.035545982961, 5596.300462442195, 5602.215689803431, 5610.5231055360755, 5699.572532060112, 5702.857428564236, 5705.600607957486, 5723.141528770718, 5281.471852814688, 5401.540869026019, 5403.865188310341, 5427.267318911896, 5436.782528698966, 5571.303587051619, 5585.245991791056, 5585.385607583082, 5594.321703489019, 5604.467575580314, 5827.621204621686, 5856.3558008618165, 5890.6760128191645, 5903.380145084876, 5914.881702366015, 3949.815055483401, 4071.6742660586488, 4077.89805254871, 4082.7958602069184, 4089.117172393515, 5551.861203469945, 5557.5553955682635, 5563.805266815642, 5570.470805273492, 5609.775608975595, 5433.701146436984, 5442.591805614674, 5453.345598528202, 5464.316960379952, 5466.234727138283, 5202.557782588522, 5429.647072940246, 5467.535259502828, 5473.2063850742215, 5494.532964698045, 5456.236126942311, 5460.2800117962715, 5475.397706252229, 5480.861949228307, 5497.725113744217, 5297.920531192112, 5486.341639003185, 5510.641808282448, 5526.723663816712, 5537.5569954402745, 5320.733963301742, 5346.465353464622, 5348.679079255257, 5355.651882627617, 5362.892742145057], "value": 4245.575249895965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.1336369629054, 743.758278559468, 805.6455159729722, 814.3770015937807, 828.8673812190051, 15114.244287785608, 16192.0, 16232.0, 16241.593960151002, 16370.18149092545, 14927.32827022784, 15172.0, 15180.848191518082, 15183.772243416357, 15207.619809504768, 773.9303462688371, 800.5556915911667, 819.4796304346723, 823.3742355809568, 830.0330115415534, 829.8049958259804, 830.8462934357133, 831.4304701279613, 831.7837362285788, 832.6253186066264, 857.0401150711212, 862.06465984407, 862.3144600042981, 862.3316929379749, 862.4135587800293, 847.3052097280232, 848.8726690996339, 854.2824027816632, 855.482433127958, 856.2379482260793, 623.7660877171061, 624.0171729526014, 624.5784095735361, 625.2528228766625, 625.5214760708062, 14598.489052883204, 14604.561863144123, 14605.78091328631, 14857.47998820036, 14950.102993820406, 12996.740065198737, 13647.385867635881, 13710.0, 13713.382897769625, 13735.313234338342, 11522.654320370402, 12121.090918181084, 12132.818007729891, 12219.511219551207, 12706.682332941751, 3672.320620685155, 3674.2467794102145, 3811.104390468238, 3819.751716138442, 3890.2025084857537, 71347.64326178387, 71372.99999999948, 72473.6376318115, 73406.0, 74218.62890685567, 10148.086672199537, 10295.433751143759, 10302.896971030345, 10447.94776026115, 10760.354378737144, 17896.55258618519, 17913.10434478271, 18015.27938882456, 18024.459266222035, 18026.098695065193, 7405.740799072114, 7477.513961592479, 7669.923300767033, 7715.807104822312, 7725.150233474314, 4186.874393768192, 4217.683673724483, 4293.463317085364, 4298.26929421999, 4433.312836510346, 3294.258791771832, 3361.4957756336744, 3380.0704806178496, 3384.2385463270566, 3388.678075582816, 576.2882839692976, 580.2398857496693, 582.6533212738414, 589.4311988930617, 589.5165963909639, 683.3780960907172, 739.903812504371, 745.1416762707227, 746.6565379925187, 757.3903008078549, 9357.578908949114, 9702.223822094216, 9870.555824987892, 9958.502074896227, 10090.8486372703, 705.7247673407376, 720.9423246140296, 727.5925481730249, 732.5128789355126, 733.8605664923648, 4532.546745325441, 4849.854504364875, 4930.408350997832, 4933.333999910038, 4934.9753251233515, 73986.6300668504, 75358.62320688361, 76238.0, 78251.0, 78496.0, 65712.0, 67696.99999999901, 67880.0, 68159.3184068163, 68575.65712171506, 60462.69768651129, 60565.69717151386, 60779.39220607738, 60787.69606152029, 60822.69588652117, 9255.722328330161, 9455.716328510156, 9558.235341172829, 9575.377600455948, 9599.808003840024, 41735.165296694504, 42308.0, 42497.15005699931, 42604.78697606492, 43127.35308970431, 49371.75314123406, 51038.0, 51547.22679159741, 51642.483575163766, 51814.481855180966, 10945.890541094806, 11030.889691102986, 11187.10503159729, 11319.207655464368, 11874.643760687193, 11399.658010259705, 11652.475638596114, 11883.702907427038, 12163.0, 12465.813012804996, 11709.531618735155, 11861.644150675493, 11914.880851191378, 11964.282143071443, 12023.939880300542, 11593.884061159617, 11715.765684686088, 12126.757464850478, 12452.750944980868, 12466.7815755095, 11490.827637585277, 11985.700357491132, 12018.819717704067, 12043.0, 12154.209976351509, 8877.73366798997, 9387.85918211241, 9426.717198484057, 9583.856242156513, 9826.803463930539, 8272.875906861522, 9695.175910047725, 9729.756756081155, 9794.412335259907, 9905.504724763588], "value": 6897.895259481078, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1506.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 113.0, 114.0, 113.0, 114.0, 116.0, 114.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 126.0, 120.0, 173.0, 120.0, 121.0, 121.0, 124.0, 143.0, 121.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 162.0, 161.0, 159.0, 161.0, 161.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 104.0, 76.0, 76.0, 77.0, 75.0, 95.0, 76.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 120.0, 82.0, 83.0, 83.0, 97.0, 82.0, 82.0, 100.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 96.0, 97.0, 96.0, 95.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 55.0, 57.0, 55.0, 55.0, 57.0, 58.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 83.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 147.0, 130.0, 137.0, 129.0, 127.0, 128.0, 129.0, 175.0, 138.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 169.0, 169.0, 171.0, 170.0, 172.0, 171.0, 171.0, 171.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 63.0, 63.0, 67.0, 63.0, 64.0, 63.0, 63.0, 63.0], "value": 63.9, "unit": "score"}]}]} 21:48:42 INFO - Return code: 0 21:48:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:48:42 ERROR - # TBPL SUCCESS # 21:48:42 INFO - Running post-action listener: _resource_record_post_action 21:48:42 INFO - [mozharness: 2017-02-21 05:48:42.612201Z] Finished run-tests step (success) 21:48:42 INFO - Running post-run listener: _resource_record_post_run 21:48:42 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 364412928; Read time: 240; Write time: 368420 21:48:42 INFO - TinderboxPrint: CPU usage
13.1% 21:48:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 240 21:48:42 INFO - TinderboxPrint: I/O write bytes / time
364,412,928 / 368,420 21:48:42 INFO - TinderboxPrint: CPU idle
8,288.4 (86.9%) 21:48:42 INFO - TinderboxPrint: CPU user
1,200.6 (12.6%) 21:48:42 INFO - TinderboxPrint: Swap in / out
0 / 0 21:48:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110424064; Read time: 0; Write time: 130224 21:48:42 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 253988864; Read time: 240; Write time: 238196 21:48:43 INFO - Running post-run listener: _upload_blobber_files 21:48:43 INFO - Blob upload gear active. 21:48:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:48:43 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 21:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:48:43 INFO - (blobuploader) - INFO - Open directory for files ... 21:48:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:48:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:48:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:44 INFO - (blobuploader) - INFO - Done attempting. 21:48:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:48:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:48:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:45 INFO - (blobuploader) - INFO - Done attempting. 21:48:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:48:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:48:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:48 INFO - (blobuploader) - INFO - Done attempting. 21:48:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:48:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:48:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:49 INFO - (blobuploader) - INFO - Done attempting. 21:48:49 INFO - (blobuploader) - INFO - Iteration through files over. 21:48:49 INFO - Return code: 0 21:48:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:48:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:48:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f39a2ddc3342b76078464d37c1959b62114d4f6c82be2c6332c93378c6c12696cd4f66c9767bddd9a99b677d5fab2bbbc84703e142ca3f677560995a9b46111d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ade76ee1d18bfe53d82f6d2a222c7f62e8c3dc2705be7b0f9975aceb9fc382de98d05269b715bca94b2c1eba7f57ce13c1b2b2b1a9dc3a39c163b03bd1dc448", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb14bf16998544f1010b616a9836763bedabb34fbd529a80ab4bc95141be2a8eb83bda91ffbb26758299093b8927b0124723853c19ea511ce321dc57300c58b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67349c982d67b3196b0d88b67fb7f6c51890e869762f29b60435f3d18e2a3a72350422f5621a9f85ffb32267a5f5e705bbef745c19be74a549fa19db28694126"} 21:48:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:48:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:48:49 INFO - Contents: 21:48:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f39a2ddc3342b76078464d37c1959b62114d4f6c82be2c6332c93378c6c12696cd4f66c9767bddd9a99b677d5fab2bbbc84703e142ca3f677560995a9b46111d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ade76ee1d18bfe53d82f6d2a222c7f62e8c3dc2705be7b0f9975aceb9fc382de98d05269b715bca94b2c1eba7f57ce13c1b2b2b1a9dc3a39c163b03bd1dc448", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb14bf16998544f1010b616a9836763bedabb34fbd529a80ab4bc95141be2a8eb83bda91ffbb26758299093b8927b0124723853c19ea511ce321dc57300c58b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67349c982d67b3196b0d88b67fb7f6c51890e869762f29b60435f3d18e2a3a72350422f5621a9f85ffb32267a5f5e705bbef745c19be74a549fa19db28694126"} 21:48:49 INFO - Running post-run listener: copy_logs_to_upload_dir 21:48:49 INFO - Copying logs to upload dir... 21:48:49 INFO - mkdir: /builds/slave/test/build/upload/logs 21:48:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.035998 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-20 21:48:48.417823) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:48:48.421298) ========= 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=1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f39a2ddc3342b76078464d37c1959b62114d4f6c82be2c6332c93378c6c12696cd4f66c9767bddd9a99b677d5fab2bbbc84703e142ca3f677560995a9b46111d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ade76ee1d18bfe53d82f6d2a222c7f62e8c3dc2705be7b0f9975aceb9fc382de98d05269b715bca94b2c1eba7f57ce13c1b2b2b1a9dc3a39c163b03bd1dc448", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb14bf16998544f1010b616a9836763bedabb34fbd529a80ab4bc95141be2a8eb83bda91ffbb26758299093b8927b0124723853c19ea511ce321dc57300c58b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67349c982d67b3196b0d88b67fb7f6c51890e869762f29b60435f3d18e2a3a72350422f5621a9f85ffb32267a5f5e705bbef745c19be74a549fa19db28694126"} build_url:https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/fYgpTw-3SNGzJzueFjNOtg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f39a2ddc3342b76078464d37c1959b62114d4f6c82be2c6332c93378c6c12696cd4f66c9767bddd9a99b677d5fab2bbbc84703e142ca3f677560995a9b46111d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ade76ee1d18bfe53d82f6d2a222c7f62e8c3dc2705be7b0f9975aceb9fc382de98d05269b715bca94b2c1eba7f57ce13c1b2b2b1a9dc3a39c163b03bd1dc448", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb14bf16998544f1010b616a9836763bedabb34fbd529a80ab4bc95141be2a8eb83bda91ffbb26758299093b8927b0124723853c19ea511ce321dc57300c58b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67349c982d67b3196b0d88b67fb7f6c51890e869762f29b60435f3d18e2a3a72350422f5621a9f85ffb32267a5f5e705bbef745c19be74a549fa19db28694126"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:48:48.446172) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:48:48.446565) ========= 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=1258088b6da462a47ba2cd90000001a2-1487654113.774507-1202623802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004382 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:48:48.531853) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:48:48.532195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:48:48.532519) ========= ========= Total master_lag: 0.16 =========