builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1487371329.61 results: success (0) revision: 0930fdc4cf8e0116e19577005f57f7ec12336ced ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.605496) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.605950) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.606228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.622362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.622742) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 14:42:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2017-02-17 14:42:10 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103627 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.757236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.757557) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013159 ========= master_lag: 0.23 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:09.997109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 14:42:09.997652) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0930fdc4cf8e0116e19577005f57f7ec12336ced --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 0930fdc4cf8e0116e19577005f57f7ec12336ced --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=9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 14:42:10,463 truncating revision to first 12 chars 2017-02-17 14:42:10,464 Setting DEBUG logging. 2017-02-17 14:42:10,464 attempt 1/10 2017-02-17 14:42:10,464 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0930fdc4cf8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 14:42:11,366 unpacking tar archive at: mozilla-central-0930fdc4cf8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.217600 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 14:42:11.229168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:11.229486) ========= script_repo_revision: 0930fdc4cf8e0116e19577005f57f7ec12336ced ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:11.229845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:11.230138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:42:11.240307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-02-17 14:42:11.240591) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:42:11 INFO - MultiFileLogger online at 20170217 14:42:11 in /builds/slave/test 14:42:11 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 14:42:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:42:11 INFO - {'append_to_log': False, 14:42:11 INFO - 'base_work_dir': '/builds/slave/test', 14:42:11 INFO - 'blob_upload_branch': 'Firefox', 14:42:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:42:11 INFO - 'branch': 'Firefox', 14:42:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:42:11 INFO - 'config_files': ('talos/linux_config.py',), 14:42:11 INFO - 'default_actions': ('clobber', 14:42:11 INFO - 'read-buildbot-config', 14:42:11 INFO - 'download-and-extract', 14:42:11 INFO - 'populate-webroot', 14:42:11 INFO - 'create-virtualenv', 14:42:11 INFO - 'install', 14:42:11 INFO - 'run-tests'), 14:42:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:42:11 INFO - 'download_minidump_stackwalk': True, 14:42:11 INFO - 'download_symbols': 'ondemand', 14:42:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:42:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:42:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:42:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:42:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:42:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:42:11 INFO - 'installer_path': 'installer.exe', 14:42:11 INFO - 'log_level': 'info', 14:42:11 INFO - 'log_name': 'talos', 14:42:11 INFO - 'log_to_console': True, 14:42:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:42:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:42:11 INFO - 'opt_config_files': (), 14:42:11 INFO - 'pip_index': False, 14:42:11 INFO - 'sps_profile': False, 14:42:11 INFO - 'sps_profile_interval': 0, 14:42:11 INFO - 'suite': 'dromaeojs', 14:42:11 INFO - 'system_bits': '32', 14:42:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:42:11 INFO - 'title': 'talos-linux64-ix-040', 14:42:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:42:11 INFO - 'use_talos_json': True, 14:42:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:42:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:42:11 INFO - 'work_dir': 'build'} 14:42:11 INFO - [mozharness: 2017-02-17 22:42:11.802028Z] Running clobber step. 14:42:11 INFO - Running pre-action listener: _resource_record_pre_action 14:42:11 INFO - Running main action method: clobber 14:42:11 INFO - rmtree: /builds/slave/test/build 14:42:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:42:12 INFO - Running post-action listener: _resource_record_post_action 14:42:12 INFO - [mozharness: 2017-02-17 22:42:12.850892Z] Finished clobber step (success) 14:42:12 INFO - [mozharness: 2017-02-17 22:42:12.850992Z] Running read-buildbot-config step. 14:42:12 INFO - Running pre-action listener: _resource_record_pre_action 14:42:12 INFO - Running main action method: read_buildbot_config 14:42:12 INFO - Using buildbot properties: 14:42:12 INFO - { 14:42:12 INFO - "project": "", 14:42:12 INFO - "product": "firefox", 14:42:12 INFO - "who": "kwierso@gmail.com", 14:42:12 INFO - "installer_path": "public/build/target.tar.bz2", 14:42:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 14:42:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:42:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 14:42:12 INFO - "stage_platform": "linux64-pgo", 14:42:12 INFO - "basedir": "/builds/slave/test", 14:42:12 INFO - "buildnumber": 40, 14:42:12 INFO - "platform": "ubuntu64_hw", 14:42:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:42:12 INFO - "slavebuilddir": "test", 14:42:12 INFO - "taskId": "O04QhVduTniicvayKHS57g", 14:42:12 INFO - "branch": "mozilla-central", 14:42:12 INFO - "script_repo_revision": "production", 14:42:12 INFO - "revision": "0930fdc4cf8e0116e19577005f57f7ec12336ced", 14:42:12 INFO - "slavename": "talos-linux64-ix-040", 14:42:12 INFO - "repo_path": "mozilla-central" 14:42:12 INFO - } 14:42:12 INFO - Finding installer, test and symbols from parent task. 14:42:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O04QhVduTniicvayKHS57g'}, attempt #1 14:42:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O04QhVduTniicvayKHS57g'}, attempt #1 14:42:13 INFO - Task dependencies: amFZW7EhR9G7hKijAvLgwA 14:42:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA'}, attempt #1 14:42:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2 14:42:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/test_packages.json 14:42:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.crashreporter-symbols.zip 14:42:14 INFO - Running post-action listener: _resource_record_post_action 14:42:14 INFO - [mozharness: 2017-02-17 22:42:14.344512Z] Finished read-buildbot-config step (success) 14:42:14 INFO - [mozharness: 2017-02-17 22:42:14.344729Z] Running download-and-extract step. 14:42:14 INFO - Running pre-action listener: _resource_record_pre_action 14:42:14 INFO - Running main action method: download_and_extract 14:42:14 INFO - mkdir: /builds/slave/test/build/tests 14:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:14 INFO - trying https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.test_packages.json 14:42:14 INFO - Downloading https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:42:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:42:14 INFO - Downloaded 896 bytes. 14:42:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:42:14 INFO - Using the following test package requirements: 14:42:14 INFO - {u'common': [u'target.common.tests.zip'], 14:42:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:42:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:42:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:42:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:42:14 INFO - u'mozbase': [u'target.common.tests.zip'], 14:42:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:42:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:42:14 INFO - u'web-platform': [u'target.common.tests.zip', 14:42:14 INFO - u'target.web-platform.tests.zip'], 14:42:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:42:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:42:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.common.tests.zip 14:42:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:42:14 INFO - Fetch https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.common.tests.zip into memory 14:42:16 INFO - Expected file size: 59216174 14:42:16 INFO - Obtained file size: 59216174 14:42:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:42:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.common.tests.zip 14:42:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:42:25 INFO - Fetch https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.common.tests.zip into memory 14:42:26 INFO - Expected file size: 59216174 14:42:26 INFO - Obtained file size: 59216174 14:42:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.talos.tests.zip 14:42:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:42:37 INFO - Fetch https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.talos.tests.zip into memory 14:42:38 INFO - Expected file size: 14403647 14:42:38 INFO - Obtained file size: 14403647 14:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:38 INFO - trying https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2 14:42:38 INFO - Downloading https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:42:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:42:39 INFO - Downloaded 66712899 bytes. 14:42:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2 14:42:39 INFO - mkdir: /builds/slave/test/properties 14:42:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:42:39 INFO - Writing to file /builds/slave/test/properties/build_url 14:42:39 INFO - Contents: 14:42:39 INFO - build_url:https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2 14:42:39 INFO - Running post-action listener: _resource_record_post_action 14:42:39 INFO - Running post-action listener: set_extra_try_arguments 14:42:39 INFO - [mozharness: 2017-02-17 22:42:39.939909Z] Finished download-and-extract step (success) 14:42:39 INFO - [mozharness: 2017-02-17 22:42:39.940006Z] Running populate-webroot step. 14:42:39 INFO - Running pre-action listener: _resource_record_pre_action 14:42:39 INFO - Running main action method: populate_webroot 14:42:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:42:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:42:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:42:39 INFO - '--tppagecycles', 14:42:39 INFO - '7'], 14:42:39 INFO - 'tests': ['tsvgm']}}, 14:42:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:42:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:42:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:42:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:42:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:42:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:42:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['damp', 'tps']}, 14:42:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'tests': ['damp', 'tps']}, 14:42:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['dromaeo_dom']}, 14:42:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:42:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['basic_compositor_video']}, 14:42:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:42:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['a11yr', 14:42:39 INFO - 'ts_paint', 14:42:39 INFO - 'tpaint', 14:42:39 INFO - 'sessionrestore', 14:42:39 INFO - 'sessionrestore_no_auto_restore', 14:42:39 INFO - 'tabpaint']}, 14:42:39 INFO - 'other-e10s': {'tests': ['a11yr', 14:42:39 INFO - 'ts_paint', 14:42:39 INFO - 'tpaint', 14:42:39 INFO - 'sessionrestore', 14:42:39 INFO - 'sessionrestore_no_auto_restore', 14:42:39 INFO - 'tabpaint']}, 14:42:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:42:39 INFO - 'ts_paint', 14:42:39 INFO - 'tpaint', 14:42:39 INFO - 'sessionrestore', 14:42:39 INFO - 'sessionrestore_no_auto_restore', 14:42:39 INFO - 'tabpaint']}, 14:42:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:42:39 INFO - 'ts_paint', 14:42:39 INFO - 'tpaint', 14:42:39 INFO - 'sessionrestore', 14:42:39 INFO - 'sessionrestore_no_auto_restore', 14:42:39 INFO - 'tabpaint']}, 14:42:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['a11yr', 14:42:39 INFO - 'ts_paint', 14:42:39 INFO - 'tpaint', 14:42:39 INFO - 'sessionrestore', 14:42:39 INFO - 'sessionrestore_no_auto_restore', 14:42:39 INFO - 'tabpaint']}, 14:42:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['a11yr', 14:42:39 INFO - 'ts_paint', 14:42:39 INFO - 'tpaint', 14:42:39 INFO - 'sessionrestore', 14:42:39 INFO - 'sessionrestore_no_auto_restore', 14:42:39 INFO - 'tabpaint']}, 14:42:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['tsvgx', 14:42:39 INFO - 'tsvgr_opacity', 14:42:39 INFO - 'tart', 14:42:39 INFO - 'tscrollx', 14:42:39 INFO - 'cart', 14:42:39 INFO - 'tsvg_static']}, 14:42:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:42:39 INFO - 'tsvgr_opacity', 14:42:39 INFO - 'tart', 14:42:39 INFO - 'tscrollx', 14:42:39 INFO - 'cart', 14:42:39 INFO - 'tsvg_static']}, 14:42:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'talos_options': ['--disable-e10s'], 14:42:39 INFO - 'tests': ['tp5o']}, 14:42:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:42:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'talos_options': ['--disable-e10s', 14:42:39 INFO - '--xperf_path', 14:42:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:42:39 INFO - 'tests': ['tp5n']}, 14:42:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:42:39 INFO - 'talos_options': ['--xperf_path', 14:42:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:42:39 INFO - 'tests': ['tp5n']}}, 14:42:39 INFO - 'talos.zip': {'path': '', 14:42:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:42:39 INFO - Running post-action listener: _resource_record_post_action 14:42:39 INFO - [mozharness: 2017-02-17 22:42:39.955668Z] Finished populate-webroot step (success) 14:42:39 INFO - [mozharness: 2017-02-17 22:42:39.955801Z] Running create-virtualenv step. 14:42:39 INFO - Running pre-action listener: _resource_record_pre_action 14:42:39 INFO - Running main action method: create_virtualenv 14:42:39 INFO - Creating virtualenv /builds/slave/test/build/venv 14:42:39 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 14:42:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:42:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:42:40 INFO - Using real prefix '/usr' 14:42:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:42:40 INFO - Installing distribute.............................................................................................................................................................................................done. 14:42:42 INFO - Installing pip.................done. 14:42:42 INFO - Return code: 0 14:42:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:42:43 INFO - Reading from file tmpfile_stdout 14:42:43 INFO - Output received: 14:42:43 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) 14:42:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:42:43 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')]} 14:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:43 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')]} 14:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:43 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:43 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 14:42:43 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 14:42:43 INFO - Using env: {'DISPLAY': ':0', 14:42:43 INFO - 'HOME': '/home/cltbld', 14:42:43 INFO - 'LANG': 'en_US.UTF-8', 14:42:43 INFO - 'LANGUAGE': 'en_US:en', 14:42:43 INFO - 'LOGNAME': 'cltbld', 14:42:43 INFO - 'MAIL': '/var/mail/cltbld', 14:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:43 INFO - 'MOZ_NO_REMOTE': '1', 14:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:43 INFO - 'NO_EM_RESTART': '1', 14:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:43 INFO - 'PWD': '/builds/slave/test', 14:42:43 INFO - 'SHELL': '/bin/bash', 14:42:43 INFO - 'SHLVL': '1', 14:42:43 INFO - 'TERM': 'linux', 14:42:43 INFO - 'TMOUT': '86400', 14:42:43 INFO - 'USER': 'cltbld', 14:42:43 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:43 INFO - '_': '/tools/buildbot/bin/python'} 14:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:43 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 14:42:43 INFO - Cleaning up... 14:42:43 INFO - Return code: 0 14:42:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:42:43 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')]} 14:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:43 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')]} 14:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:43 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:43 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 14:42:43 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 14:42:43 INFO - Using env: {'DISPLAY': ':0', 14:42:43 INFO - 'HOME': '/home/cltbld', 14:42:43 INFO - 'LANG': 'en_US.UTF-8', 14:42:43 INFO - 'LANGUAGE': 'en_US:en', 14:42:43 INFO - 'LOGNAME': 'cltbld', 14:42:43 INFO - 'MAIL': '/var/mail/cltbld', 14:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:43 INFO - 'MOZ_NO_REMOTE': '1', 14:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:43 INFO - 'NO_EM_RESTART': '1', 14:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:43 INFO - 'PWD': '/builds/slave/test', 14:42:43 INFO - 'SHELL': '/bin/bash', 14:42:43 INFO - 'SHLVL': '1', 14:42:43 INFO - 'TERM': 'linux', 14:42:43 INFO - 'TMOUT': '86400', 14:42:43 INFO - 'USER': 'cltbld', 14:42:43 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:43 INFO - '_': '/tools/buildbot/bin/python'} 14:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:43 INFO - Downloading/unpacking psutil>=3.1.1 14:42: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 14:42: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 14:42: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 14:42: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 14:42: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 14:42: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 14:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:42:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:42:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:42:45 INFO - Installing collected packages: psutil 14:42:45 INFO - Running setup.py install for psutil 14:42:45 INFO - building 'psutil._psutil_linux' extension 14:42:45 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 14:42:45 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 14:42:45 INFO - building 'psutil._psutil_posix' extension 14:42:45 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 14:42:45 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 14:42:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:42:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:42:46 INFO - Successfully installed psutil 14:42:46 INFO - Cleaning up... 14:42:46 INFO - Return code: 0 14:42:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:42: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')]} 14:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42: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')]} 14:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:42: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 mozsystemmonitor==0.3 14:42:46 INFO - Using env: {'DISPLAY': ':0', 14:42:46 INFO - 'HOME': '/home/cltbld', 14:42:46 INFO - 'LANG': 'en_US.UTF-8', 14:42:46 INFO - 'LANGUAGE': 'en_US:en', 14:42:46 INFO - 'LOGNAME': 'cltbld', 14:42:46 INFO - 'MAIL': '/var/mail/cltbld', 14:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:46 INFO - 'MOZ_NO_REMOTE': '1', 14:42:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:46 INFO - 'NO_EM_RESTART': '1', 14:42:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:46 INFO - 'PWD': '/builds/slave/test', 14:42:46 INFO - 'SHELL': '/bin/bash', 14:42:46 INFO - 'SHLVL': '1', 14:42:46 INFO - 'TERM': 'linux', 14:42:46 INFO - 'TMOUT': '86400', 14:42:46 INFO - 'USER': 'cltbld', 14:42:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:46 INFO - '_': '/tools/buildbot/bin/python'} 14:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:46 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 14:42:46 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 14:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:42:48 INFO - Installing collected packages: mozsystemmonitor 14:42:48 INFO - Running setup.py install for mozsystemmonitor 14:42:48 INFO - Successfully installed mozsystemmonitor 14:42:48 INFO - Cleaning up... 14:42:48 INFO - Return code: 0 14:42:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:42:48 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')]} 14:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:48 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')]} 14:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:48 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 14:42:48 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 14:42:48 INFO - Using env: {'DISPLAY': ':0', 14:42:48 INFO - 'HOME': '/home/cltbld', 14:42:48 INFO - 'LANG': 'en_US.UTF-8', 14:42:48 INFO - 'LANGUAGE': 'en_US:en', 14:42:48 INFO - 'LOGNAME': 'cltbld', 14:42:48 INFO - 'MAIL': '/var/mail/cltbld', 14:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:48 INFO - 'MOZ_NO_REMOTE': '1', 14:42:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:48 INFO - 'NO_EM_RESTART': '1', 14:42:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:48 INFO - 'PWD': '/builds/slave/test', 14:42:48 INFO - 'SHELL': '/bin/bash', 14:42:48 INFO - 'SHLVL': '1', 14:42:48 INFO - 'TERM': 'linux', 14:42:48 INFO - 'TMOUT': '86400', 14:42:48 INFO - 'USER': 'cltbld', 14:42:48 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:48 INFO - '_': '/tools/buildbot/bin/python'} 14:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:48 INFO - Downloading/unpacking jsonschema==2.5.1 14:42:48 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 14:42:48 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 14:42:48 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 14:42:48 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 14:42:48 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 14:42:48 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 14:42:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:42:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:42:50 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 14:42:50 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 14:42:50 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 14:42:50 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 14:42:50 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 14:42:50 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 14:42:51 INFO - Downloading functools32-3.2.3-2.tar.gz 14:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:42:51 INFO - warning: no files found matching '*.txt' 14:42:51 INFO - no previously-included directories found matching 'build' 14:42:51 INFO - no previously-included directories found matching 'dist' 14:42:51 INFO - no previously-included directories found matching '.git*' 14:42:51 INFO - Installing collected packages: jsonschema, functools32 14:42:51 INFO - Running setup.py install for functools32 14:42:51 INFO - warning: no files found matching '*.txt' 14:42:51 INFO - no previously-included directories found matching 'build' 14:42:51 INFO - no previously-included directories found matching 'dist' 14:42:51 INFO - no previously-included directories found matching '.git*' 14:42:51 INFO - Successfully installed jsonschema functools32 14:42:51 INFO - Cleaning up... 14:42:51 INFO - Return code: 0 14:42:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:42:51 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')]} 14:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:51 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')]} 14:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:51 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:51 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 14:42:51 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 14:42:51 INFO - Using env: {'DISPLAY': ':0', 14:42:51 INFO - 'HOME': '/home/cltbld', 14:42:51 INFO - 'LANG': 'en_US.UTF-8', 14:42:51 INFO - 'LANGUAGE': 'en_US:en', 14:42:51 INFO - 'LOGNAME': 'cltbld', 14:42:51 INFO - 'MAIL': '/var/mail/cltbld', 14:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:51 INFO - 'MOZ_NO_REMOTE': '1', 14:42:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:51 INFO - 'NO_EM_RESTART': '1', 14:42:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:51 INFO - 'PWD': '/builds/slave/test', 14:42:51 INFO - 'SHELL': '/bin/bash', 14:42:51 INFO - 'SHLVL': '1', 14:42:51 INFO - 'TERM': 'linux', 14:42:51 INFO - 'TMOUT': '86400', 14:42:51 INFO - 'USER': 'cltbld', 14:42:51 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:51 INFO - '_': '/tools/buildbot/bin/python'} 14:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:42:51 INFO - Cleaning up... 14:42:51 INFO - Return code: 0 14:42:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:42:51 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')]} 14:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:51 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')]} 14:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:51 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:51 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 14:42:51 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 14:42:51 INFO - Using env: {'DISPLAY': ':0', 14:42:51 INFO - 'HOME': '/home/cltbld', 14:42:51 INFO - 'LANG': 'en_US.UTF-8', 14:42:51 INFO - 'LANGUAGE': 'en_US:en', 14:42:51 INFO - 'LOGNAME': 'cltbld', 14:42:51 INFO - 'MAIL': '/var/mail/cltbld', 14:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:51 INFO - 'MOZ_NO_REMOTE': '1', 14:42:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:51 INFO - 'NO_EM_RESTART': '1', 14:42:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:51 INFO - 'PWD': '/builds/slave/test', 14:42:51 INFO - 'SHELL': '/bin/bash', 14:42:51 INFO - 'SHLVL': '1', 14:42:51 INFO - 'TERM': 'linux', 14:42:51 INFO - 'TMOUT': '86400', 14:42:51 INFO - 'USER': 'cltbld', 14:42:51 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:51 INFO - '_': '/tools/buildbot/bin/python'} 14:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:51 INFO - Downloading/unpacking blobuploader==1.2.4 14:42:51 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 14:42:51 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 14:42:51 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 14:42:51 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 14:42:51 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 14:42:51 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 14:42:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:42:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:42:54 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 14:42:54 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 14:42:54 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 14:42:54 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 14:42:54 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 14:42:54 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 14:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:42:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:42:55 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 14:42:55 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 14:42:55 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 14:42:55 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 14:42:55 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 14:42:55 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 14:42:55 INFO - Downloading docopt-0.6.1.tar.gz 14:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:42:55 INFO - Installing collected packages: blobuploader, requests, docopt 14:42:55 INFO - Running setup.py install for blobuploader 14:42:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:42:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:42:55 INFO - Running setup.py install for requests 14:42:56 INFO - Running setup.py install for docopt 14:42:56 INFO - Successfully installed blobuploader requests docopt 14:42:56 INFO - Cleaning up... 14:42:56 INFO - Return code: 0 14:42:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42:56 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')]} 14:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:56 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')]} 14:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:56 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:56 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 14:42:56 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 14:42:56 INFO - Using env: {'DISPLAY': ':0', 14:42:56 INFO - 'HOME': '/home/cltbld', 14:42:56 INFO - 'LANG': 'en_US.UTF-8', 14:42:56 INFO - 'LANGUAGE': 'en_US:en', 14:42:56 INFO - 'LOGNAME': 'cltbld', 14:42:56 INFO - 'MAIL': '/var/mail/cltbld', 14:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:56 INFO - 'MOZ_NO_REMOTE': '1', 14:42:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:56 INFO - 'NO_EM_RESTART': '1', 14:42:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:56 INFO - 'PWD': '/builds/slave/test', 14:42:56 INFO - 'SHELL': '/bin/bash', 14:42:56 INFO - 'SHLVL': '1', 14:42:56 INFO - 'TERM': 'linux', 14:42:56 INFO - 'TMOUT': '86400', 14:42:56 INFO - 'USER': 'cltbld', 14:42:56 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:56 INFO - '_': '/tools/buildbot/bin/python'} 14:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:42:56 INFO - Running setup.py (path:/tmp/pip-OdNsO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:42:56 INFO - Running setup.py (path:/tmp/pip-_VECda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:42:56 INFO - Running setup.py (path:/tmp/pip-XZeulv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:42:56 INFO - Running setup.py (path:/tmp/pip-RRg5GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:42:56 INFO - Running setup.py (path:/tmp/pip-xPA5_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:42:56 INFO - Running setup.py (path:/tmp/pip-UZZlDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:42:56 INFO - Running setup.py (path:/tmp/pip-De692_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:42:56 INFO - Running setup.py (path:/tmp/pip-rnOSV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:42:56 INFO - Running setup.py (path:/tmp/pip-xLyows-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:42:57 INFO - Running setup.py (path:/tmp/pip-cKSCUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:42:57 INFO - Running setup.py (path:/tmp/pip-UoHpbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:42:57 INFO - Running setup.py (path:/tmp/pip-7Rhgre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:42:57 INFO - Running setup.py (path:/tmp/pip-Q_l3ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:42:57 INFO - Running setup.py (path:/tmp/pip-_d1T23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:57 INFO - Running setup.py (path:/tmp/pip-_1lRKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:42:57 INFO - Running setup.py (path:/tmp/pip-rAshTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:42:57 INFO - Running setup.py (path:/tmp/pip-OFdkSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:42:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:42:57 INFO - Running setup.py install for manifestparser 14:42:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:42:57 INFO - Running setup.py install for mozcrash 14:42:57 INFO - Running setup.py install for mozdebug 14:42:57 INFO - Running setup.py install for mozdevice 14:42:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for mozfile 14:42:58 INFO - Running setup.py install for mozhttpd 14:42:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for mozinfo 14:42:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for mozInstall 14:42:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for mozleak 14:42:58 INFO - Running setup.py install for mozlog 14:42:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for moznetwork 14:42:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for mozprocess 14:42:58 INFO - Running setup.py install for mozprofile 14:42:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:42:58 INFO - Running setup.py install for mozrunner 14:42:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:42:59 INFO - Running setup.py install for mozscreenshot 14:42:59 INFO - Running setup.py install for moztest 14:42:59 INFO - Running setup.py install for mozversion 14:42:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:42:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:42:59 INFO - Cleaning up... 14:42:59 INFO - Return code: 0 14:42:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42:59 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')]} 14:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:59 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')]} 14:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:59 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:42:59 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 14:42:59 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 14:42:59 INFO - Using env: {'DISPLAY': ':0', 14:42:59 INFO - 'HOME': '/home/cltbld', 14:42:59 INFO - 'LANG': 'en_US.UTF-8', 14:42:59 INFO - 'LANGUAGE': 'en_US:en', 14:42:59 INFO - 'LOGNAME': 'cltbld', 14:42:59 INFO - 'MAIL': '/var/mail/cltbld', 14:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:59 INFO - 'MOZ_NO_REMOTE': '1', 14:42:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:42:59 INFO - 'NO_EM_RESTART': '1', 14:42:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:59 INFO - 'PWD': '/builds/slave/test', 14:42:59 INFO - 'SHELL': '/bin/bash', 14:42:59 INFO - 'SHLVL': '1', 14:42:59 INFO - 'TERM': 'linux', 14:42:59 INFO - 'TMOUT': '86400', 14:42:59 INFO - 'USER': 'cltbld', 14:42:59 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:59 INFO - '_': '/tools/buildbot/bin/python'} 14:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:42:59 INFO - Running setup.py (path:/tmp/pip-eRIh1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:42:59 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)) 14:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:42:59 INFO - Running setup.py (path:/tmp/pip-byJ58J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:42:59 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)) 14:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:42:59 INFO - Running setup.py (path:/tmp/pip-bFQdts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:42:59 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)) 14:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:42:59 INFO - Running setup.py (path:/tmp/pip-vK3NdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:42:59 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)) 14:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:42:59 INFO - Running setup.py (path:/tmp/pip-f6Q1vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:43:00 INFO - Running setup.py (path:/tmp/pip-2FSBb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:43:00 INFO - Running setup.py (path:/tmp/pip-T75LV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:43:00 INFO - Running setup.py (path:/tmp/pip-twl7au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:43:00 INFO - Running setup.py (path:/tmp/pip-J07RRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:43:00 INFO - Running setup.py (path:/tmp/pip-_1JypN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:43:00 INFO - Running setup.py (path:/tmp/pip-nETHzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:43:00 INFO - Running setup.py (path:/tmp/pip-WUA8Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:43:00 INFO - Running setup.py (path:/tmp/pip-sV9hae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:43:00 INFO - Running setup.py (path:/tmp/pip-6eoe9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:00 INFO - Running setup.py (path:/tmp/pip-tmujPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:43:00 INFO - Running setup.py (path:/tmp/pip-Q8N4N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:43:00 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)) 14:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:43:00 INFO - Running setup.py (path:/tmp/pip-0WlAzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:43:00 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 14:43:00 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 14:43:00 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 14:43:00 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 14:43:00 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 14:43:00 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 14:43:03 INFO - Downloading blessings-1.6.tar.gz 14:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:43:03 INFO - Installing collected packages: blessings 14:43:03 INFO - Running setup.py install for blessings 14:43:03 INFO - Successfully installed blessings 14:43:03 INFO - Cleaning up... 14:43:03 INFO - Return code: 0 14:43:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:43:03 INFO - Reading from file tmpfile_stdout 14:43:03 INFO - Current package versions: 14:43:03 INFO - argparse == 1.2.1 14:43:03 INFO - blessings == 1.6 14:43:03 INFO - blobuploader == 1.2.4 14:43:03 INFO - docopt == 0.6.1 14:43:03 INFO - functools32 == 3.2.3-2 14:43:03 INFO - jsonschema == 2.5.1 14:43:03 INFO - manifestparser == 1.1 14:43:03 INFO - mozInstall == 1.12 14:43:03 INFO - mozcrash == 1.0 14:43:03 INFO - mozdebug == 0.1 14:43:03 INFO - mozdevice == 0.49 14:43:03 INFO - mozfile == 1.2 14:43:03 INFO - mozhttpd == 0.7 14:43:03 INFO - mozinfo == 0.9 14:43:03 INFO - mozleak == 0.1 14:43:03 INFO - mozlog == 3.4 14:43:03 INFO - moznetwork == 0.27 14:43:03 INFO - mozprocess == 0.25 14:43:03 INFO - mozprofile == 0.28 14:43:03 INFO - mozrunner == 6.13 14:43:03 INFO - mozscreenshot == 0.1 14:43:03 INFO - mozsystemmonitor == 0.3 14:43:03 INFO - moztest == 0.8 14:43:03 INFO - mozversion == 1.4 14:43:03 INFO - psutil == 3.1.1 14:43:03 INFO - requests == 1.2.3 14:43:03 INFO - wsgiref == 0.1.2 14:43:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:03 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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:43:03 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 14:43:03 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 14:43:03 INFO - Using env: {'DISPLAY': ':0', 14:43:03 INFO - 'HOME': '/home/cltbld', 14:43:03 INFO - 'LANG': 'en_US.UTF-8', 14:43:03 INFO - 'LANGUAGE': 'en_US:en', 14:43:03 INFO - 'LOGNAME': 'cltbld', 14:43:03 INFO - 'MAIL': '/var/mail/cltbld', 14:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:03 INFO - 'MOZ_NO_REMOTE': '1', 14:43:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:03 INFO - 'NO_EM_RESTART': '1', 14:43:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:03 INFO - 'PWD': '/builds/slave/test', 14:43:03 INFO - 'SHELL': '/bin/bash', 14:43:03 INFO - 'SHLVL': '1', 14:43:03 INFO - 'TERM': 'linux', 14:43:03 INFO - 'TMOUT': '86400', 14:43:03 INFO - 'USER': 'cltbld', 14:43:03 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:03 INFO - '_': '/tools/buildbot/bin/python'} 14:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 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)) 14:43:03 INFO - Cleaning up... 14:43:03 INFO - Return code: 0 14:43:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xbe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', '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 14:43:03 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 14:43:03 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 14:43:03 INFO - Using env: {'DISPLAY': ':0', 14:43:03 INFO - 'HOME': '/home/cltbld', 14:43:03 INFO - 'LANG': 'en_US.UTF-8', 14:43:03 INFO - 'LANGUAGE': 'en_US:en', 14:43:03 INFO - 'LOGNAME': 'cltbld', 14:43:03 INFO - 'MAIL': '/var/mail/cltbld', 14:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:03 INFO - 'MOZ_NO_REMOTE': '1', 14:43:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:03 INFO - 'NO_EM_RESTART': '1', 14:43:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:03 INFO - 'PWD': '/builds/slave/test', 14:43:03 INFO - 'SHELL': '/bin/bash', 14:43:03 INFO - 'SHLVL': '1', 14:43:03 INFO - 'TERM': 'linux', 14:43:03 INFO - 'TMOUT': '86400', 14:43:03 INFO - 'USER': 'cltbld', 14:43:03 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:03 INFO - '_': '/tools/buildbot/bin/python'} 14:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:43:03 INFO - Cleaning up... 14:43:03 INFO - Return code: 0 14:43:03 INFO - Running post-action listener: _resource_record_post_action 14:43:03 INFO - Running post-action listener: _start_resource_monitoring 14:43:04 INFO - Starting resource monitoring. 14:43:04 INFO - [mozharness: 2017-02-17 22:43:04.013266Z] Finished create-virtualenv step (success) 14:43:04 INFO - [mozharness: 2017-02-17 22:43:04.013839Z] Running install step. 14:43:04 INFO - Running pre-action listener: _resource_record_pre_action 14:43:04 INFO - Running main action method: install 14:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:43:04 INFO - Reading from file tmpfile_stdout 14:43:04 INFO - Detecting whether we're running mozinstall >=1.0... 14:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:43:04 INFO - Reading from file tmpfile_stdout 14:43:04 INFO - Output received: 14:43:04 INFO - Usage: mozinstall [options] installer 14:43:04 INFO - Options: 14:43:04 INFO - -h, --help show this help message and exit 14:43:04 INFO - -d DEST, --destination=DEST 14:43:04 INFO - Directory to install application into. [default: 14:43:04 INFO - "/builds/slave/test"] 14:43:04 INFO - --app=APP Application being installed. [default: firefox] 14:43:04 INFO - mkdir: /builds/slave/test/build/application 14:43:04 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'] 14:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:43:20 INFO - Reading from file tmpfile_stdout 14:43:20 INFO - Output received: 14:43:20 INFO - /builds/slave/test/build/application/firefox/firefox 14:43:20 INFO - Running post-action listener: _resource_record_post_action 14:43:20 INFO - [mozharness: 2017-02-17 22:43:20.967587Z] Finished install step (success) 14:43:20 INFO - [mozharness: 2017-02-17 22:43:20.967837Z] Running run-tests step. 14:43:20 INFO - Running pre-action listener: _resource_record_pre_action 14:43:20 INFO - Running main action method: run_tests 14:43:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:43:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:43:20 INFO - Python 2.7.3 14:43:20 INFO - Return code: 0 14:43:20 INFO - grabbing minidump binary from tooltool 14:43:20 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')]} 14:43:20 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 0xf0a540>, '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 0xe7ef70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7f400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:43:20 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 14:43:20 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 14:43:20 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 14:43:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:43:21 INFO - Return code: 0 14:43:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:43:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:43:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:43:21 INFO - ENV: RUST_BACKTRACE is now 1 14:43:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:43:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:43:21 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/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:43:21 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/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 14:43:21 INFO - Using env: {'DISPLAY': ':0', 14:43:21 INFO - 'HOME': '/home/cltbld', 14:43:21 INFO - 'LANG': 'en_US.UTF-8', 14:43:21 INFO - 'LANGUAGE': 'en_US:en', 14:43:21 INFO - 'LOGNAME': 'cltbld', 14:43:21 INFO - 'MAIL': '/var/mail/cltbld', 14:43:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:43:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:21 INFO - 'MOZ_NO_REMOTE': '1', 14:43:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:43:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:21 INFO - 'NO_EM_RESTART': '1', 14:43:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:21 INFO - 'PWD': '/builds/slave/test', 14:43:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:43:21 INFO - 'RUST_BACKTRACE': '1', 14:43:21 INFO - 'SHELL': '/bin/bash', 14:43:21 INFO - 'SHLVL': '1', 14:43:21 INFO - 'TERM': 'linux', 14:43:21 INFO - 'TMOUT': '86400', 14:43:21 INFO - 'USER': 'cltbld', 14:43:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023', 14:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:21 INFO - '_': '/tools/buildbot/bin/python'} 14:43:21 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/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:43:21 INFO - mozversion application_buildid: 20170217214152 14:43:21 INFO - mozversion application_changeset: 0930fdc4cf8e0116e19577005f57f7ec12336ced 14:43:21 INFO - mozversion application_display_name: Nightly 14:43:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:43:21 INFO - mozversion application_name: Firefox 14:43:21 INFO - mozversion application_remotingname: firefox 14:43:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:43:21 INFO - mozversion application_vendor: Mozilla 14:43:21 INFO - mozversion application_version: 54.0a1 14:43:21 INFO - mozversion platform_buildid: 20170217214152 14:43:21 INFO - mozversion platform_changeset: 0930fdc4cf8e0116e19577005f57f7ec12336ced 14:43:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:43:21 INFO - mozversion platform_version: 54.0a1 14:43:21 INFO - using testdate: 1487371401 14:43:21 INFO - actual date: 1487371401 14:43:21 INFO - starting webserver on 'localhost:50978' 14:43:21 INFO - SUITE-START | Running 2 tests 14:43:21 INFO - TEST-START | dromaeo_css 14:43:21 INFO - operating with platform_type : linux_ 14:43:21 INFO - Initialising browser for dromaeo_css test... 14:43:21 INFO - TEST-INFO | started process 14667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9kZFf1/profile http://localhost:50978/getInfo.html) 14:43:24 INFO - PROCESS | 14667 | Iterating over profile /tmp/tmp9kZFf1/profile 14:43:25 INFO - PROCESS | 14667 | __metrics Screen width/height:1600/1200 14:43:25 INFO - PROCESS | 14667 | colorDepth:24 14:43:25 INFO - PROCESS | 14667 | Browser inner width/height: 1024/697 14:43:25 INFO - PROCESS | 14667 | __metrics 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/blocklist.xml = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/places.sqlite-shm = Fri Feb 17 2017 14:43:22 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:43:25 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/content-prefs.sqlite = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/safebrowsing = Fri Feb 17 2017 14:43:25 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/places.sqlite-wal = Fri Feb 17 2017 14:43:25 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/extensions.ini = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/extensions = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/pluginreg.dat = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/permissions.sqlite = Fri Feb 17 2017 14:43:22 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/gmp = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/secmod.db = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/prefs.js = Fri Feb 17 2017 14:42:38 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/addons.json = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/compatibility.ini = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/cookies.sqlite = Fri Feb 17 2017 14:43:24 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/directoryLinks.json = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/mimeTypes.rdf = Fri Feb 17 2017 14:43:25 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | Stat failure 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/user.js = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/startupCache = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/minidumps = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/thumbnails = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/key3.db = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/cert8.db = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/places.sqlite = Fri Feb 17 2017 14:43:25 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/extensions.json = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/crashes = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/localstore.rdf = Fri Feb 17 2017 14:42:38 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/bookmarkbackups = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/cache2 = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/.parentlock = Fri Feb 17 2017 14:43:21 GMT-0800 (PST) 14:43:25 INFO - PROCESS | 14667 | No birth date. Using mtime. 14:43:25 INFO - PROCESS | 14667 | Using date: /tmp/tmp9kZFf1/profile/frequencyCap.json = Fri Feb 17 2017 14:43:23 GMT-0800 (PST) 14:43:26 INFO - PROCESS | 14667 | 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" 14:43:26 INFO - PROCESS | 14667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:43:27 INFO - TEST-INFO | 14667: exit 0 14:43:27 INFO - Browser initialized. 14:43:27 INFO - Running cycle 1/1 for dromaeo_css test... 14:43:27 INFO - TEST-INFO | started process 14761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9kZFf1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:39 INFO - PROCESS | 14761 | RSS: Main: 142774272 14:43:39 INFO - PROCESS | 14761 | 14:46:40 INFO - PROCESS | 14761 | Cycle 1(1): loaded http://localhost:50978/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50978/tests/dromaeo/cssquery-ext.html) 14:46:42 INFO - PROCESS | 14761 | RSS: Main: 306237440 14:46:42 INFO - PROCESS | 14761 | 14:48:29 INFO - PROCESS | 14761 | ************************* 14:48:29 INFO - PROCESS | 14761 | A coding exception was thrown and uncaught in a Task. 14:48:29 INFO - PROCESS | 14761 | 14:48:29 ERROR - PROCESS | 14761 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:48:29 INFO - PROCESS | 14761 | Full stack: 14:48:29 INFO - PROCESS | 14761 | ************************* 14:49:44 INFO - PROCESS | 14761 | Cycle 1(1): loaded http://localhost:50978/tests/dromaeo/cssquery-ext.html (next: http://localhost:50978/tests/dromaeo/cssquery-jquery.html) 14:49:45 INFO - PROCESS | 14761 | RSS: Main: 243732480 14:49:45 INFO - PROCESS | 14761 | 14:52:47 INFO - PROCESS | 14761 | Cycle 1(1): loaded http://localhost:50978/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50978/tests/dromaeo/cssquery-mootools.html) 14:52:48 INFO - PROCESS | 14761 | RSS: Main: 178589696 14:52:48 INFO - PROCESS | 14761 | 14:55:50 INFO - PROCESS | 14761 | Cycle 1(1): loaded http://localhost:50978/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50978/tests/dromaeo/cssquery-prototype.html) 14:55:50 INFO - PROCESS | 14761 | RSS: Main: 190361600 14:55:50 INFO - PROCESS | 14761 | 14:58:53 INFO - PROCESS | 14761 | Cycle 1(1): loaded http://localhost:50978/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50978/tests/dromaeo/cssquery-yui.html) 14:58:53 INFO - PROCESS | 14761 | RSS: Main: 182771712 14:58:53 INFO - PROCESS | 14761 | 15:01:56 INFO - PROCESS | 14761 | Cycle 1(1): loaded http://localhost:50978/tests/dromaeo/cssquery-yui.html (next: http://localhost:50978/tests/dromaeo/cssquery-dojo.html) 15:01:57 INFO - PROCESS | 14761 | RSS: Main: 211767296 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | __start_tp_report 15:01:57 INFO - PROCESS | 14761 | _x_x_mozilla_page_load 15:01:57 INFO - PROCESS | 14761 | _x_x_mozilla_page_load_details 15:01:57 INFO - PROCESS | 14761 | |i|pagename|runs| 15:01:57 INFO - PROCESS | 14761 | |0;dojo.html;3108.8290144042076;3141.057682695191;3157.7000184982417;3173.3653269346137;3181.7136457718816;5098.898022039557;5135.127028405171;5141.794328226866;5181.715005674687;5194.402643695981;5034.748262586874;5047.848564543069;5102.795888164469;5106.617003724717;5220.060389129955;4504.66215033873;4627.537246275362;4684.859454216362;4718.57532822046;4720.527947205286;4531.954680453203;4711.340412342275;4718.268668356393;4752.334673145762;4759.357486739298;4555.316702494636;4614.861554153381;4618.56123668251;4652.55800698933;4703.976480117612;4688.249880019214;4689.155951928651;4715.3398524206295;4759.286107083931;4770.16522108633;4626.676132670715;4675.532446755297;4694.647901407374;4714.528547145293;4748.572628463455;5083.618728595333;5132.435432102503;5140.948590514085;5253.579713622901;5257.789688412459;5552.250446189753;5586.022446071962;5588.748506317225;5589.608727389084;5647.491725744661;5615;5615.157726341041;5635.21107045014;5636.15457681347;5640.153976903456;5121.206213036985;5216.165413533854;5221.817236396708;5225.242339860737;5278.947210527884;5597.132444471114;5607.327120745455;5659.094544872801;5662;5681.857953551112;5509.944900550943;5528.336599608073;5529.972350138304;5535.557155427556;5581.2186293918885;5589;5604;5606.271184745957;5609.80365687199;5610.298712660918;5376.650517716336;5444.972775136178;5584.832455026356;5598.608097433222;5599.216109744639;5457.2359869618285;5503.036968530571;5530.861728456821;5579.860503487364;5589.496945274945;4330.2205602991135;4330.610245077958;4373.972116552608;4376.518582955872;4391.231534481453;3901.0637447012514;3903.6291552302487;3906.9609303907164;3909.9609003910164;3940.4680368150507;5209.661372010807;5311.681299121988;5316.946830531722;5319.946800532022;5363.115086010843;5589.329280486368;5590.329160500766;5601.803936862272;5605.439456054443;5607.579431542651;5270.7628156733035;5399.00118478075;5407.891842163135;5409.134538473825;5447.073997420448;4883.926741098886;4890.975545122252;4895.828645997373;4901.411830580353;4936.210206367035;5670.829875103754;5874.882502349929;5881.32364778056;5885;5892.234009578728;5473.644213126134;5540.168974653834;5548.0845660465575;5549.028919939075;5600.887982240414;5850.473457388855;5852.561057920758;5857.7949771757785;5890.587658863838;5901.439363260483;5471.452854714496;5518.42056584069;5526.834194974157;5527.226188333556;5531;5479.506844384025;5530.723463826873;5534.418886016911;5603;5607.523360514402;5433.185022246536;5443.646162999393;5451.3458384994055;5451.400345962021;5452.509274165185;5474.726263686879;5574.665520068809;5576.525995290283;5579.609427340048;5588.217649528988;5491.258680078138;5524.254225679482;5525.11598144295;5532.944670553243;5571.13647384656;5515.779368825202;5564.582656300713;5564.8330550083565;5571.749271282721;5572.749226284908;5181.248718935658;5220.3735551734035;5327.041132596015;5388.137897936312;5394.919076213939 15:01:57 INFO - PROCESS | 14761 | |1;ext.html;528.1365984126832;578.3406916115631;594.6848170469652;606.8482879280178;609.6677310865578;30610.846945765268;33956.4906526402;43247;44174.33738493925;44333.33499997503;45752;45901.31148032782;46177.53822461775;46231.76884115575;46292.76853615727;9039.593218305175;9151.908480915206;9223.07769223079;9227.630894764201;9230.76923076925;7400.444966627498;7577.772666820004;10471.84292235617;10764.946175269113;10778.29941053829;10541.6310429135;10592.947035264851;10599;10605.151587872951;10605.84091238632;10775.245732798707;10795.29830561011;10810.67567972962;10814.026737593615;10836.67489975302;10608.575656973711;10634.042936135707;10661.84007239892;10675.41285229312;10678;42211.788941055405;42223.36664950028;42434.878256087075;42472.36291455631;42520.78739606313;3876.7286289959716;3934.4688467056867;3945.6251656092627;3980.6019398060253;4020.557738648746;29956.251093722614;31784.523232151536;31833.52249716256;31937.4528221714;32434;3783.5459744830523;3863.459115723801;3898.5516665583536;3907.765534067965;3992.514036182161;302349;302386.0108677716;302496;303339;304108;58374.41625583775;58643.4135658638;58723.41276587179;58783.412165878646;58791.70604146952;60985.69507152436;61191;61196.69401652963;61435.38564614297;61615.38384616186;16194.75707864383;16354.836451635332;16391.344346226255;16431.753523697153;16511.917440412722;11008;12627.43176557057;12707.745845083047;12791.616251512469;12794.10441269121;13749.175049497062;15794.763078553831;16132.75800862988;16147.111908845127;16699.91650041742;4978.4025916890205;5764.423557644286;5798.507126894261;5875.853103672443;5991.610545314627;855.3798496090409;878.3895192840914;881.1628952495155;913.2191975860676;921.9631214751334;30245.848770756005;30333.24166895801;30360.54459183114;30584;30743.23141921425;1043.8068957242783;1069.8020866139789;1079.222959469181;1084.8318510630866;1100.1968562948932;15587;15995.280212390471;15997;16242;16257.51227463178;416309;419732;420122;420303;420396;50633;51705.74147129316;58378.416215838144;59369.999999999134;59571.404285957455;1356.9932150339184;1364.9454021839215;1394.2262044565364;1396.8882489400823;1401.4184113592949;29957.850210748806;31602.683973159976;31893.68106318907;32038.67961320357;32157.517637235927;50671;51367.229491558406;51436.74281628717;51459.74270128625;51503.74248128735;363257;364022;364089;365014;365226;38491.807540962116;39074;39173;39178.6082139175;39231.6076839228;45057;45680.54319456763;46111.53888461207;46942.29586556136;47019.76490117527;36164.819175903955;36798.44802328021;37531.2493750118;39862;40021.59978400179;35459.29081418409;35835.64164358323;35854.28291434104;36126.59404822058;36873;36483;36874.20635317392;37310.253794924494;37482.81258593799;46598.30102548397;38687.80656096814;39735.80132099321;42187;42350.576494235895;42476.78761606172;32336.35327293488;33190.834045829615;33578.49632255567;33651.66075202349;33739.662603373654 15:01:57 INFO - PROCESS | 14761 | |2;jquery.html;381.4240496849756;401.8533235369091;425.7956181033105;428.4601402233186;444.65983522605194;13004.28476433797;14542.709145817078;19299.13153908074;19950.30173943912;20321.49196270094;20372.796272037274;20635;20806.375808725763;20980.580388392224;21118.15527378903;5733.713314334287;5875.353711091777;5923.792667256647;5924.229850119489;6077.1491991121275;3755.4742336072973;3930.508686414198;5824.621399609033;5884.882302353951;5942.821715348525;6150.138980542705;6370.076338930852;6373;6490.993895946112;6491.383318584727;6361.522885783562;6395.296517383084;6476.352364763533;6494.642794646293;6511.902321465182;5695;5713.171590119409;5714.714264286769;5726.79956201531;5729.369769325371;23712.76287237123;23889;23990.640140397907;24246.15138470163;24248.272551823473;2772.916812495628;2775.0415410485293;2794.762445192161;2797.958030629542;2838.0776247719573;13982.58052258434;14971.326290316962;15237.238138093051;15250.466233681767;15371.308291126927;1885.5859740954954;1890.3856246719733;1897.4876783268578;1919.251491918153;1936.2738972885168;97771.51114244455;103572;103714.48142759313;105026;105738.47130764373;19071.61856762857;19360.80639193618;19716.89179085891;19842.20631174737;19940.800591994186;36188;36190;36342.63657363445;36402;36557.63442365595;9427.717168484956;9612.327137100336;9751.46461919906;9982.800343993225;10053.79892402148;7748.263914928073;7824.6870125194355;7895.092064412666;7999.920000800034;8126.146754590801;9826.75433114178;10065.949670251748;10302.381857088598;10390.532426040847;10448.738781530523;3423.400904841643;4061.89845253871;4142.378643203483;4150.792460376969;4156.355764856392;480.1741005470585;483.1568912248079;484.8521201033719;839.2656425627575;894.5259012723267;12177.452014659362;12636.684082897817;12719.109662323548;18498.260069597334;20097.19611215535;913.5292179590854;933.4585940154694;935.3218916285774;937.95310234488;941.9481928493999;6659.633720145439;7053.929460705327;7152.964235178895;7300.56196628204;10125.266178131864;561256;563130;564798.7600619952;582131;596296;91990.54004730067;97591.51204244075;98822.5058874701;99099;100057;510.2524801166309;521.0932976620728;529.7298377827259;529.8622358186822;532.0715351711289;11949.581764638544;12005.699857503632;12072.818907716215;12098.213616114925;12511.874881251071;33073.33853322968;41592.37611435771;42812;42938.14123717446;43081.138377231655;55706;56775.71612141913;57231.71384143052;57518.7124064377;59088;8353.707620233263;8476.618552165044;8754.781130471534;9224.354295199271;9293.535323233677;10504;13859;13900.37448314808;14153.09861478415;14427.3507692152;11474.254173478697;11716;11731.589394371156;11866.881331186923;12129.818052729393;10922.344659320466;11216.719582010515;11728.179027467995;11776.587819426279;12070.577529786415;11310.264832785842;11449.828252576051;11545.653630391102;11837.467313971065;11922.880771192524;9119.772005699911;9169.449833010041;9303.674371396968;9567.742867533163;9663.323567350219;9537.952310238681;11244.212905096565;11249.100071994384;11275.774484510428;11432.599859004895 15:01:57 INFO - PROCESS | 14761 | |3;mootools.html;1396.1692792788288;1404.368034384527;1404.9578512644625;1414.1020452012974;1415.2428450778834;7558.168601453843;8020.157883422237;8021.8769372287925;8023.4383593148505;8037.678492860286;7770.922290777091;7822.295993360596;8023.39824513161;8024.3179329756895;8256.050554186271;2197.461621902632;2218.389942765736;2227.187076717003;2230.542738738555;2235.307054813005;1788.6154476787488;2075.7094006839056;2104.0531760707745;2161.8594791338514;2215.977840221601;1839.5033340997988;2170.305502239284;2220.7668194839553;2231.542533780579;2238.820894328458;2090.362439455965;2108.736407949006;2113.5244569971787;2120.0883620043373;2121.140937920145;2126.51090249242;2170.5007848195005;2172.1420039084474;2172.2180015194517;2174.4781252499442;8242.752717418427;8324.62539185738;8331.875021874677;8334.291585215264;8338.708145214889;4889.217725163957;4976.328195693607;4987.825426110068;5011.699298042129;5023.296738456619;2898.695636958131;3073.631164260303;3100.689931006904;3124.7968882022597;3127.890523831655;1131.2759833706434;1173.8943495085398;1186.1933884958225;1225.4852961756085;1236.3879879459635;10629.202809789296;10631.308964917333;10699.893001069968;10702.518386672618;10703.83944240837;4013.237484877864;4210.410542524049;4259.595338442843;4261.616454519108;4271.765052922057;8888.37781355312;8905.643774249087;8910.064443233498;8918.375713700108;8928.598213080428;4871.878203044952;4884.023195360871;4892.48628893961;4903.509649035139;4904.239842824368;4352.586504282088;4410.89727568108;4472.843450479218;4623.398958135421;4633.86098417048;3460.723142148622;3498.2303893143467;3519.155402703384;3551.893443196708;3599.9820000899826;1089.4171618184234;1089.5587287148717;1093.7648405592677;1110.622388387952;1123.9606613768478;738.5162718419336;742.2206682982846;743.5947408662292;759.3355813662935;886.9290456763443;5186.5850731941355;5483.725813709299;5596.636218645775;5637.408072152447;5658.801941932095;930.2372054914905;932.8367535681365;934.9205317547949;943.7546237978175;950.5912457643142;3722.106694393326;3725.3666876630496;3804.2391521695217;3837.347650899298;3848.11493356524;9471.10024547667;9765.072318129767;9797.11825935655;9821.066998635119;9841.065098815601;9764.316497845082;9791.608335666637;9793.314467987315;9809.117179453884;9823.852642210373;7551.962240188764;7611.238876112345;7662.1571627120975;7770.45606807518;7794.571298578519;5298.2052692096495;5371.113766228528;5400.891982160413;5476.835694929158;5595.916061259003;8751.299896008431;8919.821603567763;8931.19619234272;8943.195112439911;8990.91009089927;8558.82882342362;8909.465432074096;8925.062868398725;9020.188183063556;9129.087091290818;7090.4682148840075;7230.24082471354;7284.635768211462;7287.307705767944;7287.8178045547165;7772.339351155215;7780.805479863048;7841.843163136591;7842.882356764539;7855.253750893656;6851.657417129024;6883.242843287134;6951.617661028489;6988.266232045766;7058.11773528309;6981.860362792817;7089.220185779457;7095.929040709527;7096.290370962863;7317.195108538163;6272.435480806749;6375.968120159369;6384.361563843579;6386.616802991835;6406.13517175194;4556.567126123013;4618.815247390066;4636.83771068011;4669.4863565007145;4698.107359601665;4813.278008298783;4842;4881;4906.656534042726;5034.4462109167225 15:01:57 INFO - PROCESS | 14761 | |4;prototype.html;384.1855266834312;406.2768272474994;406.71529929049666;408.5092881329043;410.78023257557214;5028.597712183026;5175.223716442535;5277.604179686525;5284.973575132124;5304.151335786275;5036.420811606667;5128.5640720538795;5164.380274367072;5211.114110601197;5218.973905130479;4121.855735049274;4149.273877071516;4164.875053748392;4165.604267594589;4165.854195103165;4052.7365721228175;4151.792410379484;4174.22776786294;4189.434426352434;4190.601892820192;4099.8770036898795;4120.75275483472;4131.421600975866;4134.710570260068;4147.398627199054;4075.49056367954;4090.7954602270065;4127.277726397883;4133.855315063988;4151.418801367811;3780;3785.716071294664;3787.394016957301;3806.9428958565645;3808.6953043756434;4851.223804191312;5072;5072.391313042422;5112.616553758446;5212.40057393397;4882.3408839806525;4894.1679914414635;4897.902041959176;4898.975505122487;4915.820203151254;5534.146061069547;5536.806211782568;5545.916811247835;5571.777128914839;5572.575929470653;4048.392741088831;4215.87352379426;4226.323788193905;4238.936415953764;4240.109576988836;5672.858178545528;5726.141078838166;5730.570207234474;5737.483626473638;5768.567357448208;4377.124575084996;4395.296752519582;4404.2512772828695;4412.911741765147;4418.64650827933;4271.67962402821;4340.283853164193;4373.9125217495475;4428.892776805798;4511.345854851028;4903.523720521185;4905.558499735041;4909.926351104737;4935.185694360391;4951.554360107608;5565.359983601857;5572.108462645957;5649;5668.121441176624;5688.829335119953;1449.3043339197245;1487.947921822731;1503.6541595433112;1504.601280660626;1513.8259100203618;1053;1088.8366744988157;1092.8797832238447;1103.420704130338;1104.2601457023814;5236.879063979095;5254.297403823556;5256.290400795843;5270.8931124463525;5282.339707536558;5496.416684158439;5506.972465137729;5517.944820551824;5520.978618955506;5526.696031718214;4868.683535570177;5235.869103272373;5251.76367063483;5262.2632831403635;5263.0000299942885;2909.3744844858034;2914;2914.5281632775736;2917.226934862263;2924.1812292558116;5693.743781529841;5766.8269951900675;5773.1814478439155;5774.422557744276;5785.739641716133;5253.658512196695;5555.972220138954;5592.021396255639;5606;5622.064554811648;5720.0541721013715;5758.683272420057;5770.451807078321;5800.939342460295;5811.593188476851;5163;5443.510084092451;5483.7806487740045;5513.04286978133;5544.445555444423;5405.648632838853;5414.945850541444;5422.701751403711;5437.646552974044;5440.537554307928;5754.223179870832;5780.682062486603;5787.623804452697;5788.205656246409;5798;4137.793110344411;4215.725977811432;4234.87295381139;4260.829566817292;4300.505441874225;5442.727863606883;5449.251040152677;5452.2651983389915;5510.366307874646;5511.476409741068;5322.456487618547;5325.174597937326;5371.865703357447;5375.56995440356;5404.7297635118875;5190.48095190493;5410.3507579090765;5504.779808807763;5515.200295957144;5532.612717109763;5481.506664400223;5488.725563721878;5490.9250819541185;5496.1755736639825;5508.944910550843;5267.4469180737005;5580.581456390706;5608.355039170548;5611.831645050655;5613.971930140487;5381.434949330264;5421.566274698093;5423.267858839006;5427.023135835588;5439.864003399946 15:01:57 INFO - PROCESS | 14761 | |5;yui.html;748.786595820191;775.2054144501885;810.2424233341824;819.7622689420068;837.6356285016019;15566.37734490619;16422.507324780276;16434.096124713134;16434.424795132174;16532.83467165328;14647.121172729643;14839.629009274773;14849.92575037126;14864.777028344557;14905;791.6872835230095;792.1484404265409;806.0367860406816;811.0632219786156;814.3322475570035;809.3363441977581;810.0117856215408;810.092696180276;810.4124509730457;811.679386642276;859.2438653984516;859.5143743784773;859.70769938221;860.1527495417009;860.178529504325;850.4727069217063;850.5024560632038;850.5364576305897;851.1403482482675;851.7061613743249;618.1778234947509;618.3723520626568;618.7834258009706;618.848382146377;619.0775744141242;13777.586672399844;13849.584512464642;13967.511137110152;13997.720045599133;14038;13875;14287.142771433748;14555.417783288762;14633.831872099037;14655.193964331958;11959.222650527774;12031.836135204294;12176.208546444454;12210.389480525939;12282;3701.38927077032;3736.2527494500937;3798;3887.5918028607157;3935.3506671399186;70470.64764676195;72304;72993.27006729918;73379.63310183414;74030;10816.459177041117;10837.891621083845;10878.891211087946;10944.507497162647;10951.616693415852;21853.235136770138;21867.671984920238;21871.67192492114;21875.562488750136;21892.671609925863;7339.192688804227;7785.221477852169;7844.921550784533;7849.450538462253;7854.685812567547;4021.3163762160502;4149.3775933609595;4229.154169166179;4272.551382104834;4300.6129448349175;3689.9262014759556;3801.1447424329303;3801.4487899254455;3802.543694756647;3804.4673745675627;615.7844754336036;617.2346290599714;631.4569470255576;636.4653690899667;641.8844607970517;613.1477246627192;620.9875802483925;624.4099326136757;624.8156793745856;628.6887990444668;9333.673321433716;10166;10259.846102308471;10310.536025878704;10939.343639381663;734.6951015328685;738.3871386748986;774.4927072767347;774.5546310871272;801.234820746186;4632.467266264355;4762.261849413347;4788.715522146636;4812.446568644581;4850.708957462563;58296.417035829945;70536.294637054;71908;74063.25936740672;75656.24343756602;59383.70308148518;59643.70178149082;60129;60406;63090.36909630937;54726.45273547293;56227.15659265114;56396.43603563912;56403;56761.14858277129;10258.538365773707;10367.170626349724;10426.426546540013;10571.788564228826;10620.20348473852;39554.406683899186;39583.40624890571;39820.60179398169;40499.3925091118;41489.792551037055;44423.55576444194;44805.32792008188;45748;46058;47645.52354476411;10976.506057227603;11029.22795404314;11312.773744524899;13009.739805203653;13949.16305021702;12481.56314528987;12830.29433381173;12841.871581284442;12971.286579238233;13326.666833329244;12329.876701232874;12455.750884982068;12863.614091577268;12869.742605148032;13039.957613139957;13286.100066764337;13320.467181312639;13608;13906.51327203543;14355.928220359248;13493.595192144252;13593.184408935496;13969.778448045136;14132.293385330899;14296.57110286693;10677.359358438518;10784.338122944044;10876.45617719095;10927.890721093005;10994.835077473685;9610.615575376907;9808;10038.447885366375;11891.643250702493;12012.63962081139 15:01:57 INFO - PROCESS | 14761 | __end_tp_report 15:01:57 INFO - PROCESS | 14761 | __start_cc_report 15:01:57 INFO - PROCESS | 14761 | _x_x_mozilla_cycle_collect,1594 15:01:57 INFO - PROCESS | 14761 | __end_cc_report 15:01:57 INFO - PROCESS | 14761 | __startTimestamp1487372517174__endTimestamp 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | ------- Summary: start ------- 15:01:57 INFO - PROCESS | 14761 | Number of tests: 6 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | [#0] dojo.html Cycles:165 Average:5194.49 Median:5444.97 stddev:578.11 (10.6%) stddev-sans-first:556.25 15:01:57 INFO - PROCESS | 14761 | Values: 3108.8 3141.1 3157.7 3173.4 3181.7 5098.9 5135.1 5141.8 5181.7 5194.4 5034.7 5047.8 5102.8 5106.6 5220.1 4504.7 4627.5 4684.9 4718.6 4720.5 4532.0 4711.3 4718.3 4752.3 4759.4 4555.3 4614.9 4618.6 4652.6 4704.0 4688.2 4689.2 4715.3 4759.3 4770.2 4626.7 4675.5 4694.6 4714.5 4748.6 5083.6 5132.4 5140.9 5253.6 5257.8 5552.3 5586.0 5588.7 5589.6 5647.5 5615.0 5615.2 5635.2 5636.2 5640.2 5121.2 5216.2 5221.8 5225.2 5278.9 5597.1 5607.3 5659.1 5662.0 5681.9 5509.9 5528.3 5530.0 5535.6 5581.2 5589.0 5604.0 5606.3 5609.8 5610.3 5376.7 5445.0 5584.8 5598.6 5599.2 5457.2 5503.0 5530.9 5579.9 5589.5 4330.2 4330.6 4374.0 4376.5 4391.2 3901.1 3903.6 3907.0 3910.0 3940.5 5209.7 5311.7 5316.9 5319.9 5363.1 5589.3 5590.3 5601.8 5605.4 5607.6 5270.8 5399.0 5407.9 5409.1 5447.1 4883.9 4891.0 4895.8 4901.4 4936.2 5670.8 5874.9 5881.3 5885.0 5892.2 5473.6 5540.2 5548.1 5549.0 5600.9 5850.5 5852.6 5857.8 5890.6 5901.4 5471.5 5518.4 5526.8 5527.2 5531.0 5479.5 5530.7 5534.4 5603.0 5607.5 5433.2 5443.6 5451.3 5451.4 5452.5 5474.7 5574.7 5576.5 5579.6 5588.2 5491.3 5524.3 5525.1 5532.9 5571.1 5515.8 5564.6 5564.8 5571.7 5572.7 5181.2 5220.4 5327.0 5388.1 5394.9 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | [#1] ext.html Cycles:180 Average:53785.27 Median:37507.03 stddev:95990.58 (255.9%) stddev-sans-first:96176.57 15:01:57 INFO - PROCESS | 14761 | Values: 528.1 578.3 594.7 606.8 609.7 30610.8 33956.5 43247.0 44174.3 44333.3 45752.0 45901.3 46177.5 46231.8 46292.8 9039.6 9151.9 9223.1 9227.6 9230.8 7400.4 7577.8 10471.8 10764.9 10778.3 10541.6 10592.9 10599.0 10605.2 10605.8 10775.2 10795.3 10810.7 10814.0 10836.7 10608.6 10634.0 10661.8 10675.4 10678.0 42211.8 42223.4 42434.9 42472.4 42520.8 3876.7 3934.5 3945.6 3980.6 4020.6 29956.3 31784.5 31833.5 31937.5 32434.0 3783.5 3863.5 3898.6 3907.8 3992.5 302349.0 302386.0 302496.0 303339.0 304108.0 58374.4 58643.4 58723.4 58783.4 58791.7 60985.7 61191.0 61196.7 61435.4 61615.4 16194.8 16354.8 16391.3 16431.8 16511.9 11008.0 12627.4 12707.7 12791.6 12794.1 13749.2 15794.8 16132.8 16147.1 16699.9 4978.4 5764.4 5798.5 5875.9 5991.6 855.4 878.4 881.2 913.2 922.0 30245.8 30333.2 30360.5 30584.0 30743.2 1043.8 1069.8 1079.2 1084.8 1100.2 15587.0 15995.3 15997.0 16242.0 16257.5 416309.0 419732.0 420122.0 420303.0 420396.0 50633.0 51705.7 58378.4 59370.0 59571.4 1357.0 1364.9 1394.2 1396.9 1401.4 29957.9 31602.7 31893.7 32038.7 32157.5 50671.0 51367.2 51436.7 51459.7 51503.7 363257.0 364022.0 364089.0 365014.0 365226.0 38491.8 39074.0 39173.0 39178.6 39231.6 45057.0 45680.5 46111.5 46942.3 47019.8 36164.8 36798.4 37531.2 39862.0 40021.6 35459.3 35835.6 35854.3 36126.6 36873.0 36483.0 36874.2 37310.3 37482.8 46598.3 38687.8 39735.8 42187.0 42350.6 42476.8 32336.4 33190.8 33578.5 33651.7 33739.7 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | [#2] jquery.html Cycles:180 Average:32953.55 Median:2231.88 stddev:94570.70 (4237.3%) stddev-sans-first:94804.37 15:01:57 INFO - PROCESS | 14761 | Values: 381.4 401.9 425.8 428.5 444.7 13004.3 14542.7 19299.1 19950.3 20321.5 20372.8 20635.0 20806.4 20980.6 21118.2 5733.7 5875.4 5923.8 5924.2 6077.1 3755.5 3930.5 5824.6 5884.9 5942.8 6150.1 6370.1 6373.0 6491.0 6491.4 6361.5 6395.3 6476.4 6494.6 6511.9 5695.0 5713.2 5714.7 5726.8 5729.4 23712.8 23889.0 23990.6 24246.2 24248.3 2772.9 2775.0 2794.8 2798.0 2838.1 13982.6 14971.3 15237.2 15250.5 15371.3 1885.6 1890.4 1897.5 1919.3 1936.3 97771.5 103572.0 103714.5 105026.0 105738.5 19071.6 19360.8 19716.9 19842.2 19940.8 36188.0 36190.0 36342.6 36402.0 36557.6 9427.7 9612.3 9751.5 9982.8 10053.8 7748.3 7824.7 7895.1 7999.9 8126.1 9826.8 10065.9 10302.4 10390.5 10448.7 3423.4 4061.9 4142.4 4150.8 4156.4 480.2 483.2 484.9 839.3 894.5 12177.5 12636.7 12719.1 18498.3 20097.2 913.5 933.5 935.3 938.0 941.9 6659.6 7053.9 7153.0 7300.6 10125.3 561256.0 563130.0 564798.8 582131.0 596296.0 91990.5 97591.5 98822.5 99099.0 100057.0 510.3 521.1 529.7 529.9 532.1 11949.6 12005.7 12072.8 12098.2 12511.9 33073.3 41592.4 42812.0 42938.1 43081.1 55706.0 56775.7 57231.7 57518.7 59088.0 8353.7 8476.6 8754.8 9224.4 9293.5 10504.0 13859.0 13900.4 14153.1 14427.4 11474.3 11716.0 11731.6 11866.9 12129.8 10922.3 11216.7 11728.2 11776.6 12070.6 11310.3 11449.8 11545.7 11837.5 11922.9 9119.8 9169.4 9303.7 9567.7 9663.3 9538.0 11244.2 11249.1 11275.8 11432.6 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | [#3] mootools.html Cycles:180 Average:5273.91 Median:5242.40 stddev:2926.11 (55.8%) stddev-sans-first:2919.81 15:01:57 INFO - PROCESS | 14761 | Values: 1396.2 1404.4 1405.0 1414.1 1415.2 7558.2 8020.2 8021.9 8023.4 8037.7 7770.9 7822.3 8023.4 8024.3 8256.1 2197.5 2218.4 2227.2 2230.5 2235.3 1788.6 2075.7 2104.1 2161.9 2216.0 1839.5 2170.3 2220.8 2231.5 2238.8 2090.4 2108.7 2113.5 2120.1 2121.1 2126.5 2170.5 2172.1 2172.2 2174.5 8242.8 8324.6 8331.9 8334.3 8338.7 4889.2 4976.3 4987.8 5011.7 5023.3 2898.7 3073.6 3100.7 3124.8 3127.9 1131.3 1173.9 1186.2 1225.5 1236.4 10629.2 10631.3 10699.9 10702.5 10703.8 4013.2 4210.4 4259.6 4261.6 4271.8 8888.4 8905.6 8910.1 8918.4 8928.6 4871.9 4884.0 4892.5 4903.5 4904.2 4352.6 4410.9 4472.8 4623.4 4633.9 3460.7 3498.2 3519.2 3551.9 3600.0 1089.4 1089.6 1093.8 1110.6 1124.0 738.5 742.2 743.6 759.3 886.9 5186.6 5483.7 5596.6 5637.4 5658.8 930.2 932.8 934.9 943.8 950.6 3722.1 3725.4 3804.2 3837.3 3848.1 9471.1 9765.1 9797.1 9821.1 9841.1 9764.3 9791.6 9793.3 9809.1 9823.9 7552.0 7611.2 7662.2 7770.5 7794.6 5298.2 5371.1 5400.9 5476.8 5595.9 8751.3 8919.8 8931.2 8943.2 8990.9 8558.8 8909.5 8925.1 9020.2 9129.1 7090.5 7230.2 7284.6 7287.3 7287.8 7772.3 7780.8 7841.8 7842.9 7855.3 6851.7 6883.2 6951.6 6988.3 7058.1 6981.9 7089.2 7095.9 7096.3 7317.2 6272.4 6376.0 6384.4 6386.6 6406.1 4556.6 4618.8 4636.8 4669.5 4698.1 4813.3 4842.0 4881.0 4906.7 5034.4 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | [#4] prototype.html Cycles:180 Average:4665.67 Median:5236.37 stddev:1305.09 (24.9%) stddev-sans-first:1268.57 15:01:57 INFO - PROCESS | 14761 | Values: 384.2 406.3 406.7 408.5 410.8 5028.6 5175.2 5277.6 5285.0 5304.2 5036.4 5128.6 5164.4 5211.1 5219.0 4121.9 4149.3 4164.9 4165.6 4165.9 4052.7 4151.8 4174.2 4189.4 4190.6 4099.9 4120.8 4131.4 4134.7 4147.4 4075.5 4090.8 4127.3 4133.9 4151.4 3780.0 3785.7 3787.4 3806.9 3808.7 4851.2 5072.0 5072.4 5112.6 5212.4 4882.3 4894.2 4897.9 4899.0 4915.8 5534.1 5536.8 5545.9 5571.8 5572.6 4048.4 4215.9 4226.3 4238.9 4240.1 5672.9 5726.1 5730.6 5737.5 5768.6 4377.1 4395.3 4404.3 4412.9 4418.6 4271.7 4340.3 4373.9 4428.9 4511.3 4903.5 4905.6 4909.9 4935.2 4951.6 5565.4 5572.1 5649.0 5668.1 5688.8 1449.3 1487.9 1503.7 1504.6 1513.8 1053.0 1088.8 1092.9 1103.4 1104.3 5236.9 5254.3 5256.3 5270.9 5282.3 5496.4 5507.0 5517.9 5521.0 5526.7 4868.7 5235.9 5251.8 5262.3 5263.0 2909.4 2914.0 2914.5 2917.2 2924.2 5693.7 5766.8 5773.2 5774.4 5785.7 5253.7 5556.0 5592.0 5606.0 5622.1 5720.1 5758.7 5770.5 5800.9 5811.6 5163.0 5443.5 5483.8 5513.0 5544.4 5405.6 5414.9 5422.7 5437.6 5440.5 5754.2 5780.7 5787.6 5788.2 5798.0 4137.8 4215.7 4234.9 4260.8 4300.5 5442.7 5449.3 5452.3 5510.4 5511.5 5322.5 5325.2 5371.9 5375.6 5404.7 5190.5 5410.4 5504.8 5515.2 5532.6 5481.5 5488.7 5490.9 5496.2 5508.9 5267.4 5580.6 5608.4 5611.8 5614.0 5381.4 5421.6 5423.3 5427.0 5439.9 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - PROCESS | 14761 | [#5] yui.html Cycles:180 Average:16335.43 Median:22260.35 stddev:19923.54 (89.5%) stddev-sans-first:19945.05 15:01:57 INFO - PROCESS | 14761 | Values: 748.8 775.2 810.2 819.8 837.6 15566.4 16422.5 16434.1 16434.4 16532.8 14647.1 14839.6 14849.9 14864.8 14905.0 791.7 792.1 806.0 811.1 814.3 809.3 810.0 810.1 810.4 811.7 859.2 859.5 859.7 860.2 860.2 850.5 850.5 850.5 851.1 851.7 618.2 618.4 618.8 618.8 619.1 13777.6 13849.6 13967.5 13997.7 14038.0 13875.0 14287.1 14555.4 14633.8 14655.2 11959.2 12031.8 12176.2 12210.4 12282.0 3701.4 3736.3 3798.0 3887.6 3935.4 70470.6 72304.0 72993.3 73379.6 74030.0 10816.5 10837.9 10878.9 10944.5 10951.6 21853.2 21867.7 21871.7 21875.6 21892.7 7339.2 7785.2 7844.9 7849.5 7854.7 4021.3 4149.4 4229.2 4272.6 4300.6 3689.9 3801.1 3801.4 3802.5 3804.5 615.8 617.2 631.5 636.5 641.9 613.1 621.0 624.4 624.8 628.7 9333.7 10166.0 10259.8 10310.5 10939.3 734.7 738.4 774.5 774.6 801.2 4632.5 4762.3 4788.7 4812.4 4850.7 58296.4 70536.3 71908.0 74063.3 75656.2 59383.7 59643.7 60129.0 60406.0 63090.4 54726.5 56227.2 56396.4 56403.0 56761.1 10258.5 10367.2 10426.4 10571.8 10620.2 39554.4 39583.4 39820.6 40499.4 41489.8 44423.6 44805.3 45748.0 46058.0 47645.5 10976.5 11029.2 11312.8 13009.7 13949.2 12481.6 12830.3 12841.9 12971.3 13326.7 12329.9 12455.8 12863.6 12869.7 13040.0 13286.1 13320.5 13608.0 13906.5 14355.9 13493.6 13593.2 13969.8 14132.3 14296.6 10677.4 10784.3 10876.5 10927.9 10994.8 9610.6 9808.0 10038.4 11891.6 12012.6 15:01:57 INFO - PROCESS | 14761 | -------- Summary: end -------- 15:01:57 INFO - PROCESS | 14761 | 15:01:57 INFO - TEST-INFO | 14761: exit 0 15:01:57 INFO - TEST-OK | dromaeo_css | took 1116680ms 15:01:57 INFO - TEST-START | kraken 15:01:57 INFO - operating with platform_type : linux_ 15:01:57 INFO - Initialising browser for kraken test... 15:01:57 INFO - TEST-INFO | started process 15078 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMYlvyv/profile http://localhost:50978/getInfo.html) 15:02:01 INFO - PROCESS | 15078 | Iterating over profile /tmp/tmpMYlvyv/profile 15:02:01 INFO - PROCESS | 15078 | __metrics Screen width/height:1600/1200 15:02:01 INFO - PROCESS | 15078 | colorDepth:24 15:02:01 INFO - PROCESS | 15078 | Browser inner width/height: 1024/697 15:02:01 INFO - PROCESS | 15078 | __metrics 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/blocklist.xml = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/places.sqlite-shm = Fri Feb 17 2017 15:01:59 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/sessionCheckpoints.json = Fri Feb 17 2017 15:02:01 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/content-prefs.sqlite = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/safebrowsing = Fri Feb 17 2017 15:02:02 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/places.sqlite-wal = Fri Feb 17 2017 15:02:02 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/extensions.ini = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/extensions = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/pluginreg.dat = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/permissions.sqlite = Fri Feb 17 2017 15:01:59 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/gmp = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/secmod.db = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/prefs.js = Fri Feb 17 2017 14:42:38 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/addons.json = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/compatibility.ini = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/cookies.sqlite = Fri Feb 17 2017 15:02:01 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/directoryLinks.json = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/mimeTypes.rdf = Fri Feb 17 2017 15:02:01 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | Stat failure 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/user.js = Fri Feb 17 2017 15:01:57 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/startupCache = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/minidumps = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/thumbnails = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/key3.db = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/cert8.db = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/places.sqlite = Fri Feb 17 2017 15:02:02 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/extensions.json = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/crashes = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/localstore.rdf = Fri Feb 17 2017 14:42:38 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/bookmarkbackups = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/cache2 = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/.parentlock = Fri Feb 17 2017 15:01:58 GMT-0800 (PST) 15:02:02 INFO - PROCESS | 15078 | No birth date. Using mtime. 15:02:02 INFO - PROCESS | 15078 | Using date: /tmp/tmpMYlvyv/profile/frequencyCap.json = Fri Feb 17 2017 15:02:00 GMT-0800 (PST) 15:02:03 INFO - PROCESS | 15078 | 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" 15:02:03 INFO - PROCESS | 15078 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:02:04 INFO - TEST-INFO | 15078: exit 0 15:02:04 INFO - Browser initialized. 15:02:04 INFO - Running cycle 1/1 for kraken test... 15:02:04 INFO - TEST-INFO | started process 15175 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMYlvyv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:16 INFO - PROCESS | 15175 | RSS: Main: 142807040 15:02:16 INFO - PROCESS | 15175 | 15:02:18 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50978/tests/kraken/driver.html?testName=audio-beat-detection) 15:02:19 INFO - PROCESS | 15175 | RSS: Main: 198520832 15:02:19 INFO - PROCESS | 15175 | 15:02:22 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50978/tests/kraken/driver.html?testName=audio-dft) 15:02:22 INFO - PROCESS | 15175 | RSS: Main: 177655808 15:02:22 INFO - PROCESS | 15175 | 15:02:25 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50978/tests/kraken/driver.html?testName=audio-fft) 15:02:26 INFO - PROCESS | 15175 | RSS: Main: 258916352 15:02:26 INFO - PROCESS | 15175 | 15:02:28 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50978/tests/kraken/driver.html?testName=audio-oscillator) 15:02:28 INFO - PROCESS | 15175 | RSS: Main: 171536384 15:02:29 INFO - PROCESS | 15175 | 15:02:31 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50978/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:02:31 INFO - PROCESS | 15175 | RSS: Main: 174321664 15:02:31 INFO - PROCESS | 15175 | 15:02:34 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50978/tests/kraken/driver.html?testName=imaging-darkroom) 15:02:35 INFO - PROCESS | 15175 | RSS: Main: 214003712 15:02:35 INFO - PROCESS | 15175 | 15:02:38 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50978/tests/kraken/driver.html?testName=imaging-desaturate) 15:02:39 INFO - PROCESS | 15175 | RSS: Main: 213843968 15:02:39 INFO - PROCESS | 15175 | 15:02:42 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50978/tests/kraken/driver.html?testName=json-parse-financial) 15:02:42 INFO - PROCESS | 15175 | RSS: Main: 212410368 15:02:42 INFO - PROCESS | 15175 | 15:02:44 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50978/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:02:44 INFO - PROCESS | 15175 | RSS: Main: 174342144 15:02:44 INFO - PROCESS | 15175 | 15:02:46 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:02:47 INFO - PROCESS | 15175 | RSS: Main: 181886976 15:02:47 INFO - PROCESS | 15175 | 15:02:49 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:02:49 INFO - PROCESS | 15175 | RSS: Main: 190701568 15:02:49 INFO - PROCESS | 15175 | 15:02:52 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:02:53 INFO - PROCESS | 15175 | RSS: Main: 199581696 15:02:53 INFO - PROCESS | 15175 | 15:02:56 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:02:56 INFO - PROCESS | 15175 | RSS: Main: 196411392 15:02:56 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | Cycle 1(1): loaded http://localhost:50978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50978/tests/kraken/driver.html?testName=ai-astar) 15:02:58 INFO - PROCESS | 15175 | RSS: Main: 248950784 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | __start_tp_report 15:02:58 INFO - PROCESS | 15175 | _x_x_mozilla_page_load 15:02:58 INFO - PROCESS | 15175 | _x_x_mozilla_page_load_details 15:02:58 INFO - PROCESS | 15175 | |i|pagename|runs| 15:02:58 INFO - PROCESS | 15175 | |0;ai-astar;112;113;115;112;112;115;111;114;111;112 15:02:58 INFO - PROCESS | 15175 | |1;audio-beat-detection;145;121;121;165;127;126;121;126;154;137 15:02:58 INFO - PROCESS | 15175 | |2;audio-dft;162;161;161;161;161;161;162;163;161;162 15:02:58 INFO - PROCESS | 15175 | |3;audio-fft;75;76;77;110;76;77;75;77;96;79 15:02:58 INFO - PROCESS | 15175 | |4;audio-oscillator;82;82;104;82;82;82;97;82;83;112 15:02:58 INFO - PROCESS | 15175 | |5;imaging-gaussian-blur;114;113;113;113;113;114;113;113;113;114 15:02:58 INFO - PROCESS | 15175 | |6;imaging-darkroom;147;148;149;147;146;148;148;149;147;147 15:02:58 INFO - PROCESS | 15175 | |7;imaging-desaturate;95;96;96;96;96;96;96;96;96;96 15:02:58 INFO - PROCESS | 15175 | |8;json-parse-financial;55;54;61;53;54;55;54;54;55;55 15:02:58 INFO - PROCESS | 15175 | |9;json-stringify-tinderbox;64;65;64;64;64;64;63;65;65;68 15:02:58 INFO - PROCESS | 15175 | |10;stanford-crypto-aes;87;92;85;93;89;84;84;83;84;84 15:02:58 INFO - PROCESS | 15175 | |11;stanford-crypto-ccm;140;131;149;137;128;137;131;132;181;135 15:02:58 INFO - PROCESS | 15175 | |12;stanford-crypto-pbkdf2;173;175;180;173;175;171;174;176;172;173 15:02:58 INFO - PROCESS | 15175 | |13;stanford-crypto-sha256-iterative;65;65;65;63;64;72;65;69;68;63 15:02:58 INFO - PROCESS | 15175 | __end_tp_report 15:02:58 INFO - PROCESS | 15175 | __start_cc_report 15:02:58 INFO - PROCESS | 15175 | _x_x_mozilla_cycle_collect,1728 15:02:58 INFO - PROCESS | 15175 | __end_cc_report 15:02:58 INFO - PROCESS | 15175 | __startTimestamp1487372578773__endTimestamp 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | ------- Summary: start ------- 15:02:58 INFO - PROCESS | 15175 | Number of tests: 14 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#0] ai-astar Cycles:10 Average:112.70 Median:112.50 stddev:1.49 (1.3%) stddev-sans-first:1.56 15:02:58 INFO - PROCESS | 15175 | Values: 112.0 113.0 115.0 112.0 112.0 115.0 111.0 114.0 111.0 112.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:132.00 stddev:15.54 (11.8%) stddev-sans-first:16.00 15:02:58 INFO - PROCESS | 15175 | Values: 145.0 121.0 121.0 165.0 127.0 126.0 121.0 126.0 154.0 137.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 15:02:58 INFO - PROCESS | 15175 | Values: 162.0 161.0 161.0 161.0 161.0 161.0 162.0 163.0 161.0 162.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#3] audio-fft Cycles:10 Average:81.80 Median:77.00 stddev:11.71 (15.2%) stddev-sans-first:12.16 15:02:58 INFO - PROCESS | 15175 | Values: 75.0 76.0 77.0 110.0 76.0 77.0 75.0 77.0 96.0 79.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:82.00 stddev:11.29 (13.8%) stddev-sans-first:11.71 15:02:58 INFO - PROCESS | 15175 | Values: 82.0 82.0 104.0 82.0 82.0 82.0 97.0 82.0 83.0 112.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 15:02:58 INFO - PROCESS | 15175 | Values: 114.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 114.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.97 (0.7%) stddev-sans-first:1.00 15:02:58 INFO - PROCESS | 15175 | Values: 147.0 148.0 149.0 147.0 146.0 148.0 148.0 149.0 147.0 147.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 15:02:58 INFO - PROCESS | 15175 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:2.21 (4.0%) stddev-sans-first:2.35 15:02:58 INFO - PROCESS | 15175 | Values: 55.0 54.0 61.0 53.0 54.0 55.0 54.0 54.0 55.0 55.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:64.50 stddev:1.35 (2.1%) stddev-sans-first:1.41 15:02:58 INFO - PROCESS | 15175 | Values: 64.0 65.0 64.0 64.0 64.0 64.0 63.0 65.0 65.0 68.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.00 stddev:3.63 (4.2%) stddev-sans-first:3.84 15:02:58 INFO - PROCESS | 15175 | Values: 87.0 92.0 85.0 93.0 89.0 84.0 84.0 83.0 84.0 84.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:137.00 stddev:15.55 (11.3%) stddev-sans-first:16.49 15:02:58 INFO - PROCESS | 15175 | Values: 140.0 131.0 149.0 137.0 128.0 137.0 131.0 132.0 181.0 135.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:174.50 stddev:2.53 (1.4%) stddev-sans-first:2.65 15:02:58 INFO - PROCESS | 15175 | Values: 173.0 175.0 180.0 173.0 175.0 171.0 174.0 176.0 172.0 173.0 15:02:58 INFO - PROCESS | 15175 | 15:02:58 INFO - PROCESS | 15175 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.00 stddev:2.88 (4.4%) stddev-sans-first:3.04 15:02:58 INFO - PROCESS | 15175 | Values: 65.0 65.0 65.0 63.0 64.0 72.0 65.0 69.0 68.0 63.0 15:02:58 INFO - PROCESS | 15175 | -------- Summary: end -------- 15:02:58 INFO - PROCESS | 15175 | 15:02:59 INFO - TEST-INFO | 15175: exit 0 15:02:59 INFO - TEST-OK | kraken | took 61966ms 15:02:59 INFO - SUITE-END | took 1178s 15:02:59 INFO - Completed test suite (00:19:38) 15:02:59 INFO - javascript benchmark 15:02:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7104.116665363577, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.8290144042076, 3141.057682695191, 3157.7000184982417, 3173.3653269346137, 3181.7136457718816, 5098.898022039557, 5135.127028405171, 5141.794328226866, 5181.715005674687, 5194.402643695981, 5034.748262586874, 5047.848564543069, 5102.795888164469, 5106.617003724717, 5220.060389129955, 4504.66215033873, 4627.537246275362, 4684.859454216362, 4718.57532822046, 4720.527947205286, 4531.954680453203, 4711.340412342275, 4718.268668356393, 4752.334673145762, 4759.357486739298, 4555.316702494636, 4614.861554153381, 4618.56123668251, 4652.55800698933, 4703.976480117612, 4688.249880019214, 4689.155951928651, 4715.3398524206295, 4759.286107083931, 4770.16522108633, 4626.676132670715, 4675.532446755297, 4694.647901407374, 4714.528547145293, 4748.572628463455, 5083.618728595333, 5132.435432102503, 5140.948590514085, 5253.579713622901, 5257.789688412459, 5552.250446189753, 5586.022446071962, 5588.748506317225, 5589.608727389084, 5647.491725744661, 5615.0, 5615.157726341041, 5635.21107045014, 5636.15457681347, 5640.153976903456, 5121.206213036985, 5216.165413533854, 5221.817236396708, 5225.242339860737, 5278.947210527884, 5597.132444471114, 5607.327120745455, 5659.094544872801, 5662.0, 5681.857953551112, 5509.944900550943, 5528.336599608073, 5529.972350138304, 5535.557155427556, 5581.2186293918885, 5589.0, 5604.0, 5606.271184745957, 5609.80365687199, 5610.298712660918, 5376.650517716336, 5444.972775136178, 5584.832455026356, 5598.608097433222, 5599.216109744639, 5457.2359869618285, 5503.036968530571, 5530.861728456821, 5579.860503487364, 5589.496945274945, 4330.2205602991135, 4330.610245077958, 4373.972116552608, 4376.518582955872, 4391.231534481453, 3901.0637447012514, 3903.6291552302487, 3906.9609303907164, 3909.9609003910164, 3940.4680368150507, 5209.661372010807, 5311.681299121988, 5316.946830531722, 5319.946800532022, 5363.115086010843, 5589.329280486368, 5590.329160500766, 5601.803936862272, 5605.439456054443, 5607.579431542651, 5270.7628156733035, 5399.00118478075, 5407.891842163135, 5409.134538473825, 5447.073997420448, 4883.926741098886, 4890.975545122252, 4895.828645997373, 4901.411830580353, 4936.210206367035, 5670.829875103754, 5874.882502349929, 5881.32364778056, 5885.0, 5892.234009578728, 5473.644213126134, 5540.168974653834, 5548.0845660465575, 5549.028919939075, 5600.887982240414, 5850.473457388855, 5852.561057920758, 5857.7949771757785, 5890.587658863838, 5901.439363260483, 5471.452854714496, 5518.42056584069, 5526.834194974157, 5527.226188333556, 5531.0, 5479.506844384025, 5530.723463826873, 5534.418886016911, 5603.0, 5607.523360514402, 5433.185022246536, 5443.646162999393, 5451.3458384994055, 5451.400345962021, 5452.509274165185, 5474.726263686879, 5574.665520068809, 5576.525995290283, 5579.609427340048, 5588.217649528988, 5491.258680078138, 5524.254225679482, 5525.11598144295, 5532.944670553243, 5571.13647384656, 5515.779368825202, 5564.582656300713, 5564.8330550083565, 5571.749271282721, 5572.749226284908, 5181.248718935658, 5220.3735551734035, 5327.041132596015, 5388.137897936312, 5394.919076213939], "value": 5157.271347910462, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.1365984126832, 578.3406916115631, 594.6848170469652, 606.8482879280178, 609.6677310865578, 30610.846945765268, 33956.4906526402, 43247.0, 44174.33738493925, 44333.33499997503, 45752.0, 45901.31148032782, 46177.53822461775, 46231.76884115575, 46292.76853615727, 9039.593218305175, 9151.908480915206, 9223.07769223079, 9227.630894764201, 9230.76923076925, 7400.444966627498, 7577.772666820004, 10471.84292235617, 10764.946175269113, 10778.29941053829, 10541.6310429135, 10592.947035264851, 10599.0, 10605.151587872951, 10605.84091238632, 10775.245732798707, 10795.29830561011, 10810.67567972962, 10814.026737593615, 10836.67489975302, 10608.575656973711, 10634.042936135707, 10661.84007239892, 10675.41285229312, 10678.0, 42211.788941055405, 42223.36664950028, 42434.878256087075, 42472.36291455631, 42520.78739606313, 3876.7286289959716, 3934.4688467056867, 3945.6251656092627, 3980.6019398060253, 4020.557738648746, 29956.251093722614, 31784.523232151536, 31833.52249716256, 31937.4528221714, 32434.0, 3783.5459744830523, 3863.459115723801, 3898.5516665583536, 3907.765534067965, 3992.514036182161, 302349.0, 302386.0108677716, 302496.0, 303339.0, 304108.0, 58374.41625583775, 58643.4135658638, 58723.41276587179, 58783.412165878646, 58791.70604146952, 60985.69507152436, 61191.0, 61196.69401652963, 61435.38564614297, 61615.38384616186, 16194.75707864383, 16354.836451635332, 16391.344346226255, 16431.753523697153, 16511.917440412722, 11008.0, 12627.43176557057, 12707.745845083047, 12791.616251512469, 12794.10441269121, 13749.175049497062, 15794.763078553831, 16132.75800862988, 16147.111908845127, 16699.91650041742, 4978.4025916890205, 5764.423557644286, 5798.507126894261, 5875.853103672443, 5991.610545314627, 855.3798496090409, 878.3895192840914, 881.1628952495155, 913.2191975860676, 921.9631214751334, 30245.848770756005, 30333.24166895801, 30360.54459183114, 30584.0, 30743.23141921425, 1043.8068957242783, 1069.8020866139789, 1079.222959469181, 1084.8318510630866, 1100.1968562948932, 15587.0, 15995.280212390471, 15997.0, 16242.0, 16257.51227463178, 416309.0, 419732.0, 420122.0, 420303.0, 420396.0, 50633.0, 51705.74147129316, 58378.416215838144, 59369.999999999134, 59571.404285957455, 1356.9932150339184, 1364.9454021839215, 1394.2262044565364, 1396.8882489400823, 1401.4184113592949, 29957.850210748806, 31602.683973159976, 31893.68106318907, 32038.67961320357, 32157.517637235927, 50671.0, 51367.229491558406, 51436.74281628717, 51459.74270128625, 51503.74248128735, 363257.0, 364022.0, 364089.0, 365014.0, 365226.0, 38491.807540962116, 39074.0, 39173.0, 39178.6082139175, 39231.6076839228, 45057.0, 45680.54319456763, 46111.53888461207, 46942.29586556136, 47019.76490117527, 36164.819175903955, 36798.44802328021, 37531.2493750118, 39862.0, 40021.59978400179, 35459.29081418409, 35835.64164358323, 35854.28291434104, 36126.59404822058, 36873.0, 36483.0, 36874.20635317392, 37310.253794924494, 37482.81258593799, 46598.30102548397, 38687.80656096814, 39735.80132099321, 42187.0, 42350.576494235895, 42476.78761606172, 32336.35327293488, 33190.834045829615, 33578.49632255567, 33651.66075202349, 33739.662603373654], "value": 19857.39085840297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.4240496849756, 401.8533235369091, 425.7956181033105, 428.4601402233186, 444.65983522605194, 13004.28476433797, 14542.709145817078, 19299.13153908074, 19950.30173943912, 20321.49196270094, 20372.796272037274, 20635.0, 20806.375808725763, 20980.580388392224, 21118.15527378903, 5733.713314334287, 5875.353711091777, 5923.792667256647, 5924.229850119489, 6077.1491991121275, 3755.4742336072973, 3930.508686414198, 5824.621399609033, 5884.882302353951, 5942.821715348525, 6150.138980542705, 6370.076338930852, 6373.0, 6490.993895946112, 6491.383318584727, 6361.522885783562, 6395.296517383084, 6476.352364763533, 6494.642794646293, 6511.902321465182, 5695.0, 5713.171590119409, 5714.714264286769, 5726.79956201531, 5729.369769325371, 23712.76287237123, 23889.0, 23990.640140397907, 24246.15138470163, 24248.272551823473, 2772.916812495628, 2775.0415410485293, 2794.762445192161, 2797.958030629542, 2838.0776247719573, 13982.58052258434, 14971.326290316962, 15237.238138093051, 15250.466233681767, 15371.308291126927, 1885.5859740954954, 1890.3856246719733, 1897.4876783268578, 1919.251491918153, 1936.2738972885168, 97771.51114244455, 103572.0, 103714.48142759313, 105026.0, 105738.47130764373, 19071.61856762857, 19360.80639193618, 19716.89179085891, 19842.20631174737, 19940.800591994186, 36188.0, 36190.0, 36342.63657363445, 36402.0, 36557.63442365595, 9427.717168484956, 9612.327137100336, 9751.46461919906, 9982.800343993225, 10053.79892402148, 7748.263914928073, 7824.6870125194355, 7895.092064412666, 7999.920000800034, 8126.146754590801, 9826.75433114178, 10065.949670251748, 10302.381857088598, 10390.532426040847, 10448.738781530523, 3423.400904841643, 4061.89845253871, 4142.378643203483, 4150.792460376969, 4156.355764856392, 480.1741005470585, 483.1568912248079, 484.8521201033719, 839.2656425627575, 894.5259012723267, 12177.452014659362, 12636.684082897817, 12719.109662323548, 18498.260069597334, 20097.19611215535, 913.5292179590854, 933.4585940154694, 935.3218916285774, 937.95310234488, 941.9481928493999, 6659.633720145439, 7053.929460705327, 7152.964235178895, 7300.56196628204, 10125.266178131864, 561256.0, 563130.0, 564798.7600619952, 582131.0, 596296.0, 91990.54004730067, 97591.51204244075, 98822.5058874701, 99099.0, 100057.0, 510.2524801166309, 521.0932976620728, 529.7298377827259, 529.8622358186822, 532.0715351711289, 11949.581764638544, 12005.699857503632, 12072.818907716215, 12098.213616114925, 12511.874881251071, 33073.33853322968, 41592.37611435771, 42812.0, 42938.14123717446, 43081.138377231655, 55706.0, 56775.71612141913, 57231.71384143052, 57518.7124064377, 59088.0, 8353.707620233263, 8476.618552165044, 8754.781130471534, 9224.354295199271, 9293.535323233677, 10504.0, 13859.0, 13900.37448314808, 14153.09861478415, 14427.3507692152, 11474.254173478697, 11716.0, 11731.589394371156, 11866.881331186923, 12129.818052729393, 10922.344659320466, 11216.719582010515, 11728.179027467995, 11776.587819426279, 12070.577529786415, 11310.264832785842, 11449.828252576051, 11545.653630391102, 11837.467313971065, 11922.880771192524, 9119.772005699911, 9169.449833010041, 9303.674371396968, 9567.742867533163, 9663.323567350219, 9537.952310238681, 11244.212905096565, 11249.100071994384, 11275.774484510428, 11432.599859004895], "value": 9818.780319129903, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.1692792788288, 1404.368034384527, 1404.9578512644625, 1414.1020452012974, 1415.2428450778834, 7558.168601453843, 8020.157883422237, 8021.8769372287925, 8023.4383593148505, 8037.678492860286, 7770.922290777091, 7822.295993360596, 8023.39824513161, 8024.3179329756895, 8256.050554186271, 2197.461621902632, 2218.389942765736, 2227.187076717003, 2230.542738738555, 2235.307054813005, 1788.6154476787488, 2075.7094006839056, 2104.0531760707745, 2161.8594791338514, 2215.977840221601, 1839.5033340997988, 2170.305502239284, 2220.7668194839553, 2231.542533780579, 2238.820894328458, 2090.362439455965, 2108.736407949006, 2113.5244569971787, 2120.0883620043373, 2121.140937920145, 2126.51090249242, 2170.5007848195005, 2172.1420039084474, 2172.2180015194517, 2174.4781252499442, 8242.752717418427, 8324.62539185738, 8331.875021874677, 8334.291585215264, 8338.708145214889, 4889.217725163957, 4976.328195693607, 4987.825426110068, 5011.699298042129, 5023.296738456619, 2898.695636958131, 3073.631164260303, 3100.689931006904, 3124.7968882022597, 3127.890523831655, 1131.2759833706434, 1173.8943495085398, 1186.1933884958225, 1225.4852961756085, 1236.3879879459635, 10629.202809789296, 10631.308964917333, 10699.893001069968, 10702.518386672618, 10703.83944240837, 4013.237484877864, 4210.410542524049, 4259.595338442843, 4261.616454519108, 4271.765052922057, 8888.37781355312, 8905.643774249087, 8910.064443233498, 8918.375713700108, 8928.598213080428, 4871.878203044952, 4884.023195360871, 4892.48628893961, 4903.509649035139, 4904.239842824368, 4352.586504282088, 4410.89727568108, 4472.843450479218, 4623.398958135421, 4633.86098417048, 3460.723142148622, 3498.2303893143467, 3519.155402703384, 3551.893443196708, 3599.9820000899826, 1089.4171618184234, 1089.5587287148717, 1093.7648405592677, 1110.622388387952, 1123.9606613768478, 738.5162718419336, 742.2206682982846, 743.5947408662292, 759.3355813662935, 886.9290456763443, 5186.5850731941355, 5483.725813709299, 5596.636218645775, 5637.408072152447, 5658.801941932095, 930.2372054914905, 932.8367535681365, 934.9205317547949, 943.7546237978175, 950.5912457643142, 3722.106694393326, 3725.3666876630496, 3804.2391521695217, 3837.347650899298, 3848.11493356524, 9471.10024547667, 9765.072318129767, 9797.11825935655, 9821.066998635119, 9841.065098815601, 9764.316497845082, 9791.608335666637, 9793.314467987315, 9809.117179453884, 9823.852642210373, 7551.962240188764, 7611.238876112345, 7662.1571627120975, 7770.45606807518, 7794.571298578519, 5298.2052692096495, 5371.113766228528, 5400.891982160413, 5476.835694929158, 5595.916061259003, 8751.299896008431, 8919.821603567763, 8931.19619234272, 8943.195112439911, 8990.91009089927, 8558.82882342362, 8909.465432074096, 8925.062868398725, 9020.188183063556, 9129.087091290818, 7090.4682148840075, 7230.24082471354, 7284.635768211462, 7287.307705767944, 7287.8178045547165, 7772.339351155215, 7780.805479863048, 7841.843163136591, 7842.882356764539, 7855.253750893656, 6851.657417129024, 6883.242843287134, 6951.617661028489, 6988.266232045766, 7058.11773528309, 6981.860362792817, 7089.220185779457, 7095.929040709527, 7096.290370962863, 7317.195108538163, 6272.435480806749, 6375.968120159369, 6384.361563843579, 6386.616802991835, 6406.13517175194, 4556.567126123013, 4618.815247390066, 4636.83771068011, 4669.4863565007145, 4698.107359601665, 4813.278008298783, 4842.0, 4881.0, 4906.656534042726, 5034.4462109167225], "value": 4244.701692136394, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.1855266834312, 406.2768272474994, 406.71529929049666, 408.5092881329043, 410.78023257557214, 5028.597712183026, 5175.223716442535, 5277.604179686525, 5284.973575132124, 5304.151335786275, 5036.420811606667, 5128.5640720538795, 5164.380274367072, 5211.114110601197, 5218.973905130479, 4121.855735049274, 4149.273877071516, 4164.875053748392, 4165.604267594589, 4165.854195103165, 4052.7365721228175, 4151.792410379484, 4174.22776786294, 4189.434426352434, 4190.601892820192, 4099.8770036898795, 4120.75275483472, 4131.421600975866, 4134.710570260068, 4147.398627199054, 4075.49056367954, 4090.7954602270065, 4127.277726397883, 4133.855315063988, 4151.418801367811, 3780.0, 3785.716071294664, 3787.394016957301, 3806.9428958565645, 3808.6953043756434, 4851.223804191312, 5072.0, 5072.391313042422, 5112.616553758446, 5212.40057393397, 4882.3408839806525, 4894.1679914414635, 4897.902041959176, 4898.975505122487, 4915.820203151254, 5534.146061069547, 5536.806211782568, 5545.916811247835, 5571.777128914839, 5572.575929470653, 4048.392741088831, 4215.87352379426, 4226.323788193905, 4238.936415953764, 4240.109576988836, 5672.858178545528, 5726.141078838166, 5730.570207234474, 5737.483626473638, 5768.567357448208, 4377.124575084996, 4395.296752519582, 4404.2512772828695, 4412.911741765147, 4418.64650827933, 4271.67962402821, 4340.283853164193, 4373.9125217495475, 4428.892776805798, 4511.345854851028, 4903.523720521185, 4905.558499735041, 4909.926351104737, 4935.185694360391, 4951.554360107608, 5565.359983601857, 5572.108462645957, 5649.0, 5668.121441176624, 5688.829335119953, 1449.3043339197245, 1487.947921822731, 1503.6541595433112, 1504.601280660626, 1513.8259100203618, 1053.0, 1088.8366744988157, 1092.8797832238447, 1103.420704130338, 1104.2601457023814, 5236.879063979095, 5254.297403823556, 5256.290400795843, 5270.8931124463525, 5282.339707536558, 5496.416684158439, 5506.972465137729, 5517.944820551824, 5520.978618955506, 5526.696031718214, 4868.683535570177, 5235.869103272373, 5251.76367063483, 5262.2632831403635, 5263.0000299942885, 2909.3744844858034, 2914.0, 2914.5281632775736, 2917.226934862263, 2924.1812292558116, 5693.743781529841, 5766.8269951900675, 5773.1814478439155, 5774.422557744276, 5785.739641716133, 5253.658512196695, 5555.972220138954, 5592.021396255639, 5606.0, 5622.064554811648, 5720.0541721013715, 5758.683272420057, 5770.451807078321, 5800.939342460295, 5811.593188476851, 5163.0, 5443.510084092451, 5483.7806487740045, 5513.04286978133, 5544.445555444423, 5405.648632838853, 5414.945850541444, 5422.701751403711, 5437.646552974044, 5440.537554307928, 5754.223179870832, 5780.682062486603, 5787.623804452697, 5788.205656246409, 5798.0, 4137.793110344411, 4215.725977811432, 4234.87295381139, 4260.829566817292, 4300.505441874225, 5442.727863606883, 5449.251040152677, 5452.2651983389915, 5510.366307874646, 5511.476409741068, 5322.456487618547, 5325.174597937326, 5371.865703357447, 5375.56995440356, 5404.7297635118875, 5190.48095190493, 5410.3507579090765, 5504.779808807763, 5515.200295957144, 5532.612717109763, 5481.506664400223, 5488.725563721878, 5490.9250819541185, 5496.1755736639825, 5508.944910550843, 5267.4469180737005, 5580.581456390706, 5608.355039170548, 5611.831645050655, 5613.971930140487, 5381.434949330264, 5421.566274698093, 5423.267858839006, 5427.023135835588, 5439.864003399946], "value": 4277.214712719874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.786595820191, 775.2054144501885, 810.2424233341824, 819.7622689420068, 837.6356285016019, 15566.37734490619, 16422.507324780276, 16434.096124713134, 16434.424795132174, 16532.83467165328, 14647.121172729643, 14839.629009274773, 14849.92575037126, 14864.777028344557, 14905.0, 791.6872835230095, 792.1484404265409, 806.0367860406816, 811.0632219786156, 814.3322475570035, 809.3363441977581, 810.0117856215408, 810.092696180276, 810.4124509730457, 811.679386642276, 859.2438653984516, 859.5143743784773, 859.70769938221, 860.1527495417009, 860.178529504325, 850.4727069217063, 850.5024560632038, 850.5364576305897, 851.1403482482675, 851.7061613743249, 618.1778234947509, 618.3723520626568, 618.7834258009706, 618.848382146377, 619.0775744141242, 13777.586672399844, 13849.584512464642, 13967.511137110152, 13997.720045599133, 14038.0, 13875.0, 14287.142771433748, 14555.417783288762, 14633.831872099037, 14655.193964331958, 11959.222650527774, 12031.836135204294, 12176.208546444454, 12210.389480525939, 12282.0, 3701.38927077032, 3736.2527494500937, 3798.0, 3887.5918028607157, 3935.3506671399186, 70470.64764676195, 72304.0, 72993.27006729918, 73379.63310183414, 74030.0, 10816.459177041117, 10837.891621083845, 10878.891211087946, 10944.507497162647, 10951.616693415852, 21853.235136770138, 21867.671984920238, 21871.67192492114, 21875.562488750136, 21892.671609925863, 7339.192688804227, 7785.221477852169, 7844.921550784533, 7849.450538462253, 7854.685812567547, 4021.3163762160502, 4149.3775933609595, 4229.154169166179, 4272.551382104834, 4300.6129448349175, 3689.9262014759556, 3801.1447424329303, 3801.4487899254455, 3802.543694756647, 3804.4673745675627, 615.7844754336036, 617.2346290599714, 631.4569470255576, 636.4653690899667, 641.8844607970517, 613.1477246627192, 620.9875802483925, 624.4099326136757, 624.8156793745856, 628.6887990444668, 9333.673321433716, 10166.0, 10259.846102308471, 10310.536025878704, 10939.343639381663, 734.6951015328685, 738.3871386748986, 774.4927072767347, 774.5546310871272, 801.234820746186, 4632.467266264355, 4762.261849413347, 4788.715522146636, 4812.446568644581, 4850.708957462563, 58296.417035829945, 70536.294637054, 71908.0, 74063.25936740672, 75656.24343756602, 59383.70308148518, 59643.70178149082, 60129.0, 60406.0, 63090.36909630937, 54726.45273547293, 56227.15659265114, 56396.43603563912, 56403.0, 56761.14858277129, 10258.538365773707, 10367.170626349724, 10426.426546540013, 10571.788564228826, 10620.20348473852, 39554.406683899186, 39583.40624890571, 39820.60179398169, 40499.3925091118, 41489.792551037055, 44423.55576444194, 44805.32792008188, 45748.0, 46058.0, 47645.52354476411, 10976.506057227603, 11029.22795404314, 11312.773744524899, 13009.739805203653, 13949.16305021702, 12481.56314528987, 12830.29433381173, 12841.871581284442, 12971.286579238233, 13326.666833329244, 12329.876701232874, 12455.750884982068, 12863.614091577268, 12869.742605148032, 13039.957613139957, 13286.100066764337, 13320.467181312639, 13608.0, 13906.51327203543, 14355.928220359248, 13493.595192144252, 13593.184408935496, 13969.778448045136, 14132.293385330899, 14296.57110286693, 10677.359358438518, 10784.338122944044, 10876.45617719095, 10927.890721093005, 10994.835077473685, 9610.615575376907, 9808.0, 10038.447885366375, 11891.643250702493, 12012.63962081139], "value": 7040.500522514062, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 115.0, 112.0, 112.0, 115.0, 111.0, 114.0, 111.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 121.0, 165.0, 127.0, 126.0, 121.0, 126.0, 154.0, 137.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 163.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 110.0, 76.0, 77.0, 75.0, 77.0, 96.0, 79.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 104.0, 82.0, 82.0, 82.0, 97.0, 82.0, 83.0, 112.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 149.0, 147.0, 146.0, 148.0, 148.0, 149.0, 147.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 61.0, 53.0, 54.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 63.0, 65.0, 65.0, 68.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 85.0, 93.0, 89.0, 84.0, 84.0, 83.0, 84.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 131.0, 149.0, 137.0, 128.0, 137.0, 131.0, 132.0, 181.0, 135.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 180.0, 173.0, 175.0, 171.0, 174.0, 176.0, 172.0, 173.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 63.0, 64.0, 72.0, 65.0, 69.0, 68.0, 63.0], "value": 65.9, "unit": "score"}]}]} 15:03:00 INFO - Return code: 0 15:03:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:03:00 ERROR - # TBPL SUCCESS # 15:03:00 INFO - Running post-action listener: _resource_record_post_action 15:03:00 INFO - [mozharness: 2017-02-17 23:03:00.070799Z] Finished run-tests step (success) 15:03:00 INFO - Running post-run listener: _resource_record_post_run 15:03:00 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 366301184; Read time: 0; Write time: 363936 15:03:00 INFO - TinderboxPrint: CPU usage
13.1% 15:03:00 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:03:00 INFO - TinderboxPrint: I/O write bytes / time
366,301,184 / 363,936 15:03:00 INFO - TinderboxPrint: CPU idle
8,282.8 (86.8%) 15:03:00 INFO - TinderboxPrint: CPU user
1,207.2 (12.7%) 15:03:00 INFO - TinderboxPrint: Swap in / out
0 / 0 15:03:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112848896; Read time: 0; Write time: 155176 15:03:00 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 253001728; Read time: 0; Write time: 208760 15:03:00 INFO - Running post-run listener: _upload_blobber_files 15:03:00 INFO - Blob upload gear active. 15:03:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:03:00 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 15:03:00 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'] 15:03:00 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 15:03:01 INFO - (blobuploader) - INFO - Open directory for files ... 15:03:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:03:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:03:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:02 INFO - (blobuploader) - INFO - Done attempting. 15:03:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:03:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:03:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:05 INFO - (blobuploader) - INFO - Done attempting. 15:03:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:03:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:03:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:06 INFO - (blobuploader) - INFO - Done attempting. 15:03:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:03:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:03:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:07 INFO - (blobuploader) - INFO - Done attempting. 15:03:07 INFO - (blobuploader) - INFO - Iteration through files over. 15:03:07 INFO - Return code: 0 15:03:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:03:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:03:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40bf2172f7ae97525b18d824efbe071b9ce0356c4d0b7736e7e2b9637cfeba5c3dd8b416d101b6f447dfddfcbb63d5e9fabfb4ee50a421b00e90e9d297fdcc53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877e8706be4705bb56b20297845b52c93eee10018eb7419e983975f279376a4f31323ca590436a7fd838ca9e71cd0b0e5bb15b8285b9468bf3528cd877e149e0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcbd01134a3d5776ec6b66588554a65e0234bf43863e00ba78d78dea92c94ba831f12c4a36e621267e366d902e87489c4e2f96fa9526b87a163c5019ed2bc4e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be31f873dcdb5ae8d14fdc622ec17b99684fc23b46a43a28669f512b992b72a7cc6a879c16ad884d4ff265a499e380516510f0d4f12930a507a2931477efa639"} 15:03:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:03:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:03:07 INFO - Contents: 15:03:07 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40bf2172f7ae97525b18d824efbe071b9ce0356c4d0b7736e7e2b9637cfeba5c3dd8b416d101b6f447dfddfcbb63d5e9fabfb4ee50a421b00e90e9d297fdcc53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877e8706be4705bb56b20297845b52c93eee10018eb7419e983975f279376a4f31323ca590436a7fd838ca9e71cd0b0e5bb15b8285b9468bf3528cd877e149e0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcbd01134a3d5776ec6b66588554a65e0234bf43863e00ba78d78dea92c94ba831f12c4a36e621267e366d902e87489c4e2f96fa9526b87a163c5019ed2bc4e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be31f873dcdb5ae8d14fdc622ec17b99684fc23b46a43a28669f512b992b72a7cc6a879c16ad884d4ff265a499e380516510f0d4f12930a507a2931477efa639"} 15:03:07 INFO - Running post-run listener: copy_logs_to_upload_dir 15:03:07 INFO - Copying logs to upload dir... 15:03:07 INFO - mkdir: /builds/slave/test/build/upload/logs 15:03:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.529544 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-02-17 15:03:06.789582) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 15:03:06.792805) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40bf2172f7ae97525b18d824efbe071b9ce0356c4d0b7736e7e2b9637cfeba5c3dd8b416d101b6f447dfddfcbb63d5e9fabfb4ee50a421b00e90e9d297fdcc53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877e8706be4705bb56b20297845b52c93eee10018eb7419e983975f279376a4f31323ca590436a7fd838ca9e71cd0b0e5bb15b8285b9468bf3528cd877e149e0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcbd01134a3d5776ec6b66588554a65e0234bf43863e00ba78d78dea92c94ba831f12c4a36e621267e366d902e87489c4e2f96fa9526b87a163c5019ed2bc4e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be31f873dcdb5ae8d14fdc622ec17b99684fc23b46a43a28669f512b992b72a7cc6a879c16ad884d4ff265a499e380516510f0d4f12930a507a2931477efa639"} build_url:https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011287 build_url: 'https://queue.taskcluster.net/v1/task/amFZW7EhR9G7hKijAvLgwA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40bf2172f7ae97525b18d824efbe071b9ce0356c4d0b7736e7e2b9637cfeba5c3dd8b416d101b6f447dfddfcbb63d5e9fabfb4ee50a421b00e90e9d297fdcc53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877e8706be4705bb56b20297845b52c93eee10018eb7419e983975f279376a4f31323ca590436a7fd838ca9e71cd0b0e5bb15b8285b9468bf3528cd877e149e0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcbd01134a3d5776ec6b66588554a65e0234bf43863e00ba78d78dea92c94ba831f12c4a36e621267e366d902e87489c4e2f96fa9526b87a163c5019ed2bc4e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be31f873dcdb5ae8d14fdc622ec17b99684fc23b46a43a28669f512b992b72a7cc6a879c16ad884d4ff265a499e380516510f0d4f12930a507a2931477efa639"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 15:03:06.818145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:03:06.818499) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487371329.322164-755014023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:03:06.903155) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 15:03:06.903565) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 15:03:06.904041) ========= ========= Total master_lag: 0.38 =========