builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1487686423.19 results: success (0) revision: b69d4316561aa125612509a202922455d2de03e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.188483) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.188986) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.189262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.206559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.206993) ========= 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=62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-21 06:13:43-- 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% 26.0M=0s 2017-02-21 06:13:43 (26.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115770 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.338293) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.341895) ========= 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=62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017977 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.391787) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:43.392137) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b69d4316561aa125612509a202922455d2de03e5 --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 b69d4316561aa125612509a202922455d2de03e5 --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=62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-21 06:13:43,474 truncating revision to first 12 chars 2017-02-21 06:13:43,474 Setting DEBUG logging. 2017-02-21 06:13:43,474 attempt 1/10 2017-02-21 06:13:43,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b69d4316561a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 06:13:43,959 unpacking tar archive at: mozilla-central-b69d4316561a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.793984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:44.202411) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:44.202791) ========= script_repo_revision: b69d4316561aa125612509a202922455d2de03e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:44.203253) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:44.203521) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 06:13:44.212001) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-21 06:13:44.212339) ========= /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=62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:13:44 INFO - MultiFileLogger online at 20170221 06:13:44 in /builds/slave/test 06:13:44 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 06:13:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:13:44 INFO - {'append_to_log': False, 06:13:44 INFO - 'base_work_dir': '/builds/slave/test', 06:13:44 INFO - 'blob_upload_branch': 'Firefox', 06:13:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:13:44 INFO - 'branch': 'Firefox', 06:13:44 INFO - 'buildbot_json_path': 'buildprops.json', 06:13:44 INFO - 'config_files': ('talos/linux_config.py',), 06:13:44 INFO - 'default_actions': ('clobber', 06:13:44 INFO - 'read-buildbot-config', 06:13:44 INFO - 'download-and-extract', 06:13:44 INFO - 'populate-webroot', 06:13:44 INFO - 'create-virtualenv', 06:13:44 INFO - 'install', 06:13:44 INFO - 'run-tests'), 06:13:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:13:44 INFO - 'download_minidump_stackwalk': True, 06:13:44 INFO - 'download_symbols': 'ondemand', 06:13:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:13:44 INFO - 'tooltool.py': '/tools/tooltool.py', 06:13:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:13:44 INFO - '/tools/misc-python/virtualenv.py')}, 06:13:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:13:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:13:44 INFO - 'gecko_profile': False, 06:13:44 INFO - 'gecko_profile_interval': 0, 06:13:44 INFO - 'installer_path': 'installer.exe', 06:13:44 INFO - 'log_level': 'info', 06:13:44 INFO - 'log_name': 'talos', 06:13:44 INFO - 'log_to_console': True, 06:13:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:13:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:13:44 INFO - 'opt_config_files': (), 06:13:44 INFO - 'pip_index': False, 06:13:44 INFO - 'suite': 'dromaeojs', 06:13:44 INFO - 'system_bits': '32', 06:13:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:13:44 INFO - 'title': 'talos-linux64-ix-018', 06:13:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:13:44 INFO - 'use_talos_json': True, 06:13:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:13:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:13:44 INFO - 'work_dir': 'build'} 06:13:44 INFO - [mozharness: 2017-02-21 14:13:44.388779Z] Running clobber step. 06:13:44 INFO - Running pre-action listener: _resource_record_pre_action 06:13:44 INFO - Running main action method: clobber 06:13:44 INFO - rmtree: /builds/slave/test/build 06:13:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:13:44 INFO - Running post-action listener: _resource_record_post_action 06:13:44 INFO - [mozharness: 2017-02-21 14:13:44.844299Z] Finished clobber step (success) 06:13:44 INFO - [mozharness: 2017-02-21 14:13:44.844399Z] Running read-buildbot-config step. 06:13:44 INFO - Running pre-action listener: _resource_record_pre_action 06:13:44 INFO - Running main action method: read_buildbot_config 06:13:44 INFO - Using buildbot properties: 06:13:44 INFO - { 06:13:44 INFO - "project": "", 06:13:44 INFO - "product": "firefox", 06:13:44 INFO - "who": "cbook@mozilla.com", 06:13:44 INFO - "installer_path": "public/build/target.tar.bz2", 06:13:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 06:13:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:13:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 06:13:44 INFO - "stage_platform": "linux64-pgo", 06:13:44 INFO - "basedir": "/builds/slave/test", 06:13:44 INFO - "buildnumber": 47, 06:13:44 INFO - "platform": "ubuntu64_hw", 06:13:44 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:13:44 INFO - "slavebuilddir": "test", 06:13:44 INFO - "taskId": "H9Zf2LrLSaqYeLF8eA5eYw", 06:13:44 INFO - "branch": "mozilla-central", 06:13:44 INFO - "script_repo_revision": "production", 06:13:44 INFO - "revision": "b69d4316561aa125612509a202922455d2de03e5", 06:13:44 INFO - "slavename": "talos-linux64-ix-018", 06:13:44 INFO - "repo_path": "mozilla-central" 06:13:44 INFO - } 06:13:44 INFO - Finding installer, test and symbols from parent task. 06:13:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H9Zf2LrLSaqYeLF8eA5eYw'}, attempt #1 06:13:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H9Zf2LrLSaqYeLF8eA5eYw'}, attempt #1 06:13:45 INFO - Task dependencies: E6zG7twuRVmcmeL9CaOByA 06:13:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA'}, attempt #1 06:13:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2 06:13:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/test_packages.json 06:13:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.crashreporter-symbols.zip 06:13:46 INFO - Running post-action listener: _resource_record_post_action 06:13:46 INFO - [mozharness: 2017-02-21 14:13:46.234396Z] Finished read-buildbot-config step (success) 06:13:46 INFO - [mozharness: 2017-02-21 14:13:46.234614Z] Running download-and-extract step. 06:13:46 INFO - Running pre-action listener: _resource_record_pre_action 06:13:46 INFO - Running main action method: download_and_extract 06:13:46 INFO - mkdir: /builds/slave/test/build/tests 06:13: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')]} 06:13:46 INFO - trying https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.test_packages.json 06:13:46 INFO - Downloading https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:13:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:13:46 INFO - Downloaded 896 bytes. 06:13:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:13:46 INFO - Using the following test package requirements: 06:13:46 INFO - {u'common': [u'target.common.tests.zip'], 06:13:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:13:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:13:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:13:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:13:46 INFO - u'mozbase': [u'target.common.tests.zip'], 06:13:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:13:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:13:46 INFO - u'web-platform': [u'target.common.tests.zip', 06:13:46 INFO - u'target.web-platform.tests.zip'], 06:13:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:13:46 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:13:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.common.tests.zip 06:13:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:13:46 INFO - Fetch https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.common.tests.zip into memory 06:13:49 INFO - Expected file size: 59241190 06:13:49 INFO - Obtained file size: 59241190 06:13:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:13:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.common.tests.zip 06:13:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:13:59 INFO - Fetch https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.common.tests.zip into memory 06:14:00 INFO - Expected file size: 59241190 06:14:00 INFO - Obtained file size: 59241190 06:14:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.talos.tests.zip 06:14:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:14:11 INFO - Fetch https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.talos.tests.zip into memory 06:14:12 INFO - Expected file size: 14404319 06:14:12 INFO - Obtained file size: 14404319 06:14:13 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')]} 06:14:13 INFO - trying https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2 06:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:14:16 INFO - Downloaded 66744623 bytes. 06:14:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2 06:14:16 INFO - mkdir: /builds/slave/test/properties 06:14:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:16 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:16 INFO - Contents: 06:14:16 INFO - build_url:https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2 06:14:16 INFO - Running post-action listener: _resource_record_post_action 06:14:16 INFO - Running post-action listener: set_extra_try_arguments 06:14:16 INFO - [mozharness: 2017-02-21 14:14:16.019267Z] Finished download-and-extract step (success) 06:14:16 INFO - [mozharness: 2017-02-21 14:14:16.019420Z] Running populate-webroot step. 06:14:16 INFO - Running pre-action listener: _resource_record_pre_action 06:14:16 INFO - Running main action method: populate_webroot 06:14:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:14:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:14:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:14:16 INFO - '--tppagecycles', 06:14:16 INFO - '7'], 06:14:16 INFO - 'tests': ['tsvgm']}}, 06:14:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:14:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:14:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['damp', 'tps']}, 06:14:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'tests': ['damp', 'tps']}, 06:14:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['dromaeo_dom']}, 06:14:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['basic_compositor_video']}, 06:14:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:14:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['a11yr', 06:14:16 INFO - 'ts_paint', 06:14:16 INFO - 'tpaint', 06:14:16 INFO - 'sessionrestore', 06:14:16 INFO - 'sessionrestore_no_auto_restore', 06:14:16 INFO - 'tabpaint']}, 06:14:16 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:16 INFO - 'ts_paint', 06:14:16 INFO - 'tpaint', 06:14:16 INFO - 'sessionrestore', 06:14:16 INFO - 'sessionrestore_no_auto_restore', 06:14:16 INFO - 'tabpaint']}, 06:14:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:14:16 INFO - 'ts_paint', 06:14:16 INFO - 'tpaint', 06:14:16 INFO - 'sessionrestore', 06:14:16 INFO - 'sessionrestore_no_auto_restore', 06:14:16 INFO - 'tabpaint']}, 06:14:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:14:16 INFO - 'ts_paint', 06:14:16 INFO - 'tpaint', 06:14:16 INFO - 'sessionrestore', 06:14:16 INFO - 'sessionrestore_no_auto_restore', 06:14:16 INFO - 'tabpaint']}, 06:14:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['a11yr', 06:14:16 INFO - 'ts_paint', 06:14:16 INFO - 'tpaint', 06:14:16 INFO - 'sessionrestore', 06:14:16 INFO - 'sessionrestore_no_auto_restore', 06:14:16 INFO - 'tabpaint']}, 06:14:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['a11yr', 06:14:16 INFO - 'ts_paint', 06:14:16 INFO - 'tpaint', 06:14:16 INFO - 'sessionrestore', 06:14:16 INFO - 'sessionrestore_no_auto_restore', 06:14:16 INFO - 'tabpaint']}, 06:14:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['tsvgx', 06:14:16 INFO - 'tsvgr_opacity', 06:14:16 INFO - 'tart', 06:14:16 INFO - 'tscrollx', 06:14:16 INFO - 'cart', 06:14:16 INFO - 'tsvg_static']}, 06:14:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:16 INFO - 'tsvgr_opacity', 06:14:16 INFO - 'tart', 06:14:16 INFO - 'tscrollx', 06:14:16 INFO - 'cart', 06:14:16 INFO - 'tsvg_static']}, 06:14:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'talos_options': ['--disable-e10s'], 06:14:16 INFO - 'tests': ['tp5o']}, 06:14:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:14:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'talos_options': ['--disable-e10s', 06:14:16 INFO - '--xperf_path', 06:14:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:16 INFO - 'tests': ['tp5n']}, 06:14:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:14:16 INFO - 'talos_options': ['--xperf_path', 06:14:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:16 INFO - 'tests': ['tp5n']}}, 06:14:16 INFO - 'talos.zip': {'path': '', 06:14:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:14:16 INFO - Running post-action listener: _resource_record_post_action 06:14:16 INFO - [mozharness: 2017-02-21 14:14:16.040837Z] Finished populate-webroot step (success) 06:14:16 INFO - [mozharness: 2017-02-21 14:14:16.040931Z] Running create-virtualenv step. 06:14:16 INFO - Running pre-action listener: _resource_record_pre_action 06:14:16 INFO - Running main action method: create_virtualenv 06:14:16 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:16 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 06:14:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:14:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:16 INFO - Using real prefix '/usr' 06:14:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:16 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:18 INFO - Installing pip.................done. 06:14:18 INFO - Return code: 0 06:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:14:18 INFO - Reading from file tmpfile_stdout 06:14:18 INFO - Output received: 06:14:18 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) 06:14:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:18 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:18 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 06:14:18 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 06:14:18 INFO - Using env: {'DISPLAY': ':0', 06:14:18 INFO - 'HOME': '/home/cltbld', 06:14:18 INFO - 'LANG': 'en_US.UTF-8', 06:14:18 INFO - 'LANGUAGE': 'en_US:en', 06:14:18 INFO - 'LOGNAME': 'cltbld', 06:14:18 INFO - 'MAIL': '/var/mail/cltbld', 06:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:18 INFO - 'MOZ_NO_REMOTE': '1', 06:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:18 INFO - 'NO_EM_RESTART': '1', 06:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:18 INFO - 'PWD': '/builds/slave/test', 06:14:18 INFO - 'SHELL': '/bin/bash', 06:14:18 INFO - 'SHLVL': '1', 06:14:18 INFO - 'TERM': 'linux', 06:14:18 INFO - 'TMOUT': '86400', 06:14:18 INFO - 'USER': 'cltbld', 06:14:18 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:18 INFO - '_': '/tools/buildbot/bin/python'} 06:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:19 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 06:14:19 INFO - Cleaning up... 06:14:19 INFO - Return code: 0 06:14:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:19 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')]} 06:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:19 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')]} 06:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:19 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:19 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 06:14:19 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 06:14:19 INFO - Using env: {'DISPLAY': ':0', 06:14:19 INFO - 'HOME': '/home/cltbld', 06:14:19 INFO - 'LANG': 'en_US.UTF-8', 06:14:19 INFO - 'LANGUAGE': 'en_US:en', 06:14:19 INFO - 'LOGNAME': 'cltbld', 06:14:19 INFO - 'MAIL': '/var/mail/cltbld', 06:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:19 INFO - 'MOZ_NO_REMOTE': '1', 06:14:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:19 INFO - 'NO_EM_RESTART': '1', 06:14:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:19 INFO - 'PWD': '/builds/slave/test', 06:14:19 INFO - 'SHELL': '/bin/bash', 06:14:19 INFO - 'SHLVL': '1', 06:14:19 INFO - 'TERM': 'linux', 06:14:19 INFO - 'TMOUT': '86400', 06:14:19 INFO - 'USER': 'cltbld', 06:14:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:19 INFO - '_': '/tools/buildbot/bin/python'} 06:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:19 INFO - Downloading/unpacking psutil>=3.1.1 06:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:21 INFO - Installing collected packages: psutil 06:14:21 INFO - Running setup.py install for psutil 06:14:21 INFO - building 'psutil._psutil_linux' extension 06:14:21 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 06:14:21 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 06:14:21 INFO - building 'psutil._psutil_posix' extension 06:14:21 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 06:14:21 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 06:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:21 INFO - Successfully installed psutil 06:14:21 INFO - Cleaning up... 06:14:21 INFO - Return code: 0 06:14:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:14:21 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')]} 06:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:21 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')]} 06:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:21 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:21 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 06:14:21 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 06:14:21 INFO - Using env: {'DISPLAY': ':0', 06:14:21 INFO - 'HOME': '/home/cltbld', 06:14:21 INFO - 'LANG': 'en_US.UTF-8', 06:14:21 INFO - 'LANGUAGE': 'en_US:en', 06:14:21 INFO - 'LOGNAME': 'cltbld', 06:14:21 INFO - 'MAIL': '/var/mail/cltbld', 06:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:21 INFO - 'MOZ_NO_REMOTE': '1', 06:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:21 INFO - 'NO_EM_RESTART': '1', 06:14:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:21 INFO - 'PWD': '/builds/slave/test', 06:14:21 INFO - 'SHELL': '/bin/bash', 06:14:21 INFO - 'SHLVL': '1', 06:14:21 INFO - 'TERM': 'linux', 06:14:21 INFO - 'TMOUT': '86400', 06:14:21 INFO - 'USER': 'cltbld', 06:14:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:21 INFO - '_': '/tools/buildbot/bin/python'} 06:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:14:22 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 06:14:22 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 06:14:22 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 06:14:22 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 06:14:22 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 06:14:22 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 06:14:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:14:24 INFO - Installing collected packages: mozsystemmonitor 06:14:24 INFO - Running setup.py install for mozsystemmonitor 06:14:24 INFO - Successfully installed mozsystemmonitor 06:14:24 INFO - Cleaning up... 06:14:24 INFO - Return code: 0 06:14:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:14:24 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')]} 06:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:24 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')]} 06:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:24 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:24 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 06:14:24 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 06:14:24 INFO - Using env: {'DISPLAY': ':0', 06:14:24 INFO - 'HOME': '/home/cltbld', 06:14:24 INFO - 'LANG': 'en_US.UTF-8', 06:14:24 INFO - 'LANGUAGE': 'en_US:en', 06:14:24 INFO - 'LOGNAME': 'cltbld', 06:14:24 INFO - 'MAIL': '/var/mail/cltbld', 06:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:24 INFO - 'MOZ_NO_REMOTE': '1', 06:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:24 INFO - 'NO_EM_RESTART': '1', 06:14:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:24 INFO - 'PWD': '/builds/slave/test', 06:14:24 INFO - 'SHELL': '/bin/bash', 06:14:24 INFO - 'SHLVL': '1', 06:14:24 INFO - 'TERM': 'linux', 06:14:24 INFO - 'TMOUT': '86400', 06:14:24 INFO - 'USER': 'cltbld', 06:14:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:24 INFO - '_': '/tools/buildbot/bin/python'} 06:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:24 INFO - Downloading/unpacking jsonschema==2.5.1 06:14:24 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 06:14:24 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 06:14:24 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 06:14:24 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 06:14:24 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 06:14:24 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 06:14:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:14:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:26 INFO - Downloading functools32-3.2.3-2.tar.gz 06:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:14:26 INFO - warning: no files found matching '*.txt' 06:14:26 INFO - no previously-included directories found matching 'build' 06:14:26 INFO - no previously-included directories found matching 'dist' 06:14:26 INFO - no previously-included directories found matching '.git*' 06:14:26 INFO - Installing collected packages: jsonschema, functools32 06:14:26 INFO - Running setup.py install for functools32 06:14:26 INFO - warning: no files found matching '*.txt' 06:14:26 INFO - no previously-included directories found matching 'build' 06:14:26 INFO - no previously-included directories found matching 'dist' 06:14:26 INFO - no previously-included directories found matching '.git*' 06:14:26 INFO - Successfully installed jsonschema functools32 06:14:26 INFO - Cleaning up... 06:14:27 INFO - Return code: 0 06:14:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:14:27 INFO - Using env: {'DISPLAY': ':0', 06:14:27 INFO - 'HOME': '/home/cltbld', 06:14:27 INFO - 'LANG': 'en_US.UTF-8', 06:14:27 INFO - 'LANGUAGE': 'en_US:en', 06:14:27 INFO - 'LOGNAME': 'cltbld', 06:14:27 INFO - 'MAIL': '/var/mail/cltbld', 06:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:27 INFO - 'MOZ_NO_REMOTE': '1', 06:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:27 INFO - 'NO_EM_RESTART': '1', 06:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:27 INFO - 'PWD': '/builds/slave/test', 06:14:27 INFO - 'SHELL': '/bin/bash', 06:14:27 INFO - 'SHLVL': '1', 06:14:27 INFO - 'TERM': 'linux', 06:14:27 INFO - 'TMOUT': '86400', 06:14:27 INFO - 'USER': 'cltbld', 06:14:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:27 INFO - '_': '/tools/buildbot/bin/python'} 06:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:14:27 INFO - Cleaning up... 06:14:27 INFO - Return code: 0 06:14:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:14:27 INFO - Using env: {'DISPLAY': ':0', 06:14:27 INFO - 'HOME': '/home/cltbld', 06:14:27 INFO - 'LANG': 'en_US.UTF-8', 06:14:27 INFO - 'LANGUAGE': 'en_US:en', 06:14:27 INFO - 'LOGNAME': 'cltbld', 06:14:27 INFO - 'MAIL': '/var/mail/cltbld', 06:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:27 INFO - 'MOZ_NO_REMOTE': '1', 06:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:27 INFO - 'NO_EM_RESTART': '1', 06:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:27 INFO - 'PWD': '/builds/slave/test', 06:14:27 INFO - 'SHELL': '/bin/bash', 06:14:27 INFO - 'SHLVL': '1', 06:14:27 INFO - 'TERM': 'linux', 06:14:27 INFO - 'TMOUT': '86400', 06:14:27 INFO - 'USER': 'cltbld', 06:14:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:27 INFO - '_': '/tools/buildbot/bin/python'} 06:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:27 INFO - Downloading/unpacking blobuploader==1.2.4 06:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:29 INFO - Downloading blobuploader-1.2.4.tar.gz 06:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:14:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:14:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:30 INFO - Downloading docopt-0.6.1.tar.gz 06:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:14:30 INFO - Installing collected packages: blobuploader, requests, docopt 06:14:30 INFO - Running setup.py install for blobuploader 06:14:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:14:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:14:30 INFO - Running setup.py install for requests 06:14:30 INFO - Running setup.py install for docopt 06:14:30 INFO - Successfully installed blobuploader requests docopt 06:14:30 INFO - Cleaning up... 06:14:30 INFO - Return code: 0 06:14:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:30 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:30 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 06:14:30 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 06:14:30 INFO - Using env: {'DISPLAY': ':0', 06:14:30 INFO - 'HOME': '/home/cltbld', 06:14:30 INFO - 'LANG': 'en_US.UTF-8', 06:14:30 INFO - 'LANGUAGE': 'en_US:en', 06:14:30 INFO - 'LOGNAME': 'cltbld', 06:14:30 INFO - 'MAIL': '/var/mail/cltbld', 06:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:30 INFO - 'MOZ_NO_REMOTE': '1', 06:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:30 INFO - 'NO_EM_RESTART': '1', 06:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:30 INFO - 'PWD': '/builds/slave/test', 06:14:30 INFO - 'SHELL': '/bin/bash', 06:14:30 INFO - 'SHLVL': '1', 06:14:30 INFO - 'TERM': 'linux', 06:14:30 INFO - 'TMOUT': '86400', 06:14:30 INFO - 'USER': 'cltbld', 06:14:30 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:30 INFO - '_': '/tools/buildbot/bin/python'} 06:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:14:31 INFO - Running setup.py (path:/tmp/pip-200NqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:14:31 INFO - Running setup.py (path:/tmp/pip-142UFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:14:31 INFO - Running setup.py (path:/tmp/pip-21RLde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:14:31 INFO - Running setup.py (path:/tmp/pip-Eobq2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:14:31 INFO - Running setup.py (path:/tmp/pip-sdLY8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:14:31 INFO - Running setup.py (path:/tmp/pip-cvlg3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:14:31 INFO - Running setup.py (path:/tmp/pip-LljMkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:14:31 INFO - Running setup.py (path:/tmp/pip-SPogUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:14:31 INFO - Running setup.py (path:/tmp/pip-EQ1P0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:14:31 INFO - Running setup.py (path:/tmp/pip-99_2Ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:14:31 INFO - Running setup.py (path:/tmp/pip-3sZWeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:14:31 INFO - Running setup.py (path:/tmp/pip-RkEA0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:14:31 INFO - Running setup.py (path:/tmp/pip-orxwo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:14:32 INFO - Running setup.py (path:/tmp/pip-PTZxxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:32 INFO - Running setup.py (path:/tmp/pip-wWFg03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:14:32 INFO - Running setup.py (path:/tmp/pip-wtce13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:14:32 INFO - Running setup.py (path:/tmp/pip-unlgQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:14:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:14:32 INFO - Running setup.py install for manifestparser 06:14:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:14:32 INFO - Running setup.py install for mozcrash 06:14:32 INFO - Running setup.py install for mozdebug 06:14:32 INFO - Running setup.py install for mozdevice 06:14:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:14:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:14:32 INFO - Running setup.py install for mozfile 06:14:32 INFO - Running setup.py install for mozhttpd 06:14:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:14:32 INFO - Running setup.py install for mozinfo 06:14:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Running setup.py install for mozInstall 06:14:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Running setup.py install for mozleak 06:14:33 INFO - Running setup.py install for mozlog 06:14:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Running setup.py install for moznetwork 06:14:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Running setup.py install for mozprocess 06:14:33 INFO - Running setup.py install for mozprofile 06:14:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Running setup.py install for mozrunner 06:14:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:14:33 INFO - Running setup.py install for mozscreenshot 06:14:33 INFO - Running setup.py install for moztest 06:14:34 INFO - Running setup.py install for mozversion 06:14:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:14:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:14:34 INFO - Cleaning up... 06:14:34 INFO - Return code: 0 06:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:34 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:34 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 06:14:34 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 06:14:34 INFO - Using env: {'DISPLAY': ':0', 06:14:34 INFO - 'HOME': '/home/cltbld', 06:14:34 INFO - 'LANG': 'en_US.UTF-8', 06:14:34 INFO - 'LANGUAGE': 'en_US:en', 06:14:34 INFO - 'LOGNAME': 'cltbld', 06:14:34 INFO - 'MAIL': '/var/mail/cltbld', 06:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:34 INFO - 'MOZ_NO_REMOTE': '1', 06:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:34 INFO - 'NO_EM_RESTART': '1', 06:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:34 INFO - 'PWD': '/builds/slave/test', 06:14:34 INFO - 'SHELL': '/bin/bash', 06:14:34 INFO - 'SHLVL': '1', 06:14:34 INFO - 'TERM': 'linux', 06:14:34 INFO - 'TMOUT': '86400', 06:14:34 INFO - 'USER': 'cltbld', 06:14:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:34 INFO - '_': '/tools/buildbot/bin/python'} 06:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:14:34 INFO - Running setup.py (path:/tmp/pip-WA0Oxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:14:34 INFO - Running setup.py (path:/tmp/pip-Ev5MTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:14:34 INFO - Running setup.py (path:/tmp/pip-tlESnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:14:34 INFO - Running setup.py (path:/tmp/pip-BITfbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:14:34 INFO - Running setup.py (path:/tmp/pip-VUMeRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:14:34 INFO - Running setup.py (path:/tmp/pip-dmIIXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:14:34 INFO - Running setup.py (path:/tmp/pip-MSFX0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:14:34 INFO - Running setup.py (path:/tmp/pip-Nciz61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:14:34 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)) 06:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:14:34 INFO - Running setup.py (path:/tmp/pip-CaiSWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:14:35 INFO - Running setup.py (path:/tmp/pip-qKX0PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:14:35 INFO - Running setup.py (path:/tmp/pip-eLExgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:14:35 INFO - Running setup.py (path:/tmp/pip-DGCgaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:14:35 INFO - Running setup.py (path:/tmp/pip-5UQ7fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:14:35 INFO - Running setup.py (path:/tmp/pip-u7ko1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:35 INFO - Running setup.py (path:/tmp/pip-3MvP5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:14:35 INFO - Running setup.py (path:/tmp/pip-dHmjgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:14:35 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)) 06:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:14:35 INFO - Running setup.py (path:/tmp/pip-8XkKyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:14:35 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)) 06:14:35 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)) 06:14:35 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)) 06:14:35 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)) 06:14:35 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)) 06:14:35 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)) 06:14:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:37 INFO - Downloading blessings-1.6.tar.gz 06:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:14:37 INFO - Installing collected packages: blessings 06:14:37 INFO - Running setup.py install for blessings 06:14:37 INFO - Successfully installed blessings 06:14:37 INFO - Cleaning up... 06:14:37 INFO - Return code: 0 06:14:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:14:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:14:38 INFO - Reading from file tmpfile_stdout 06:14:38 INFO - Current package versions: 06:14:38 INFO - argparse == 1.2.1 06:14:38 INFO - blessings == 1.6 06:14:38 INFO - blobuploader == 1.2.4 06:14:38 INFO - docopt == 0.6.1 06:14:38 INFO - functools32 == 3.2.3-2 06:14:38 INFO - jsonschema == 2.5.1 06:14:38 INFO - manifestparser == 1.1 06:14:38 INFO - mozInstall == 1.12 06:14:38 INFO - mozcrash == 1.0 06:14:38 INFO - mozdebug == 0.1 06:14:38 INFO - mozdevice == 0.49 06:14:38 INFO - mozfile == 1.2 06:14:38 INFO - mozhttpd == 0.7 06:14:38 INFO - mozinfo == 0.9 06:14:38 INFO - mozleak == 0.1 06:14:38 INFO - mozlog == 3.4 06:14:38 INFO - moznetwork == 0.27 06:14:38 INFO - mozprocess == 0.25 06:14:38 INFO - mozprofile == 0.28 06:14:38 INFO - mozrunner == 6.13 06:14:38 INFO - mozscreenshot == 0.1 06:14:38 INFO - mozsystemmonitor == 0.3 06:14:38 INFO - moztest == 0.8 06:14:38 INFO - mozversion == 1.4 06:14:38 INFO - psutil == 3.1.1 06:14:38 INFO - requests == 1.2.3 06:14:38 INFO - wsgiref == 0.1.2 06:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:38 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:38 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 06:14:38 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 06:14:38 INFO - Using env: {'DISPLAY': ':0', 06:14:38 INFO - 'HOME': '/home/cltbld', 06:14:38 INFO - 'LANG': 'en_US.UTF-8', 06:14:38 INFO - 'LANGUAGE': 'en_US:en', 06:14:38 INFO - 'LOGNAME': 'cltbld', 06:14:38 INFO - 'MAIL': '/var/mail/cltbld', 06:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:38 INFO - 'MOZ_NO_REMOTE': '1', 06:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:38 INFO - 'NO_EM_RESTART': '1', 06:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:38 INFO - 'PWD': '/builds/slave/test', 06:14:38 INFO - 'SHELL': '/bin/bash', 06:14:38 INFO - 'SHLVL': '1', 06:14:38 INFO - 'TERM': 'linux', 06:14:38 INFO - 'TMOUT': '86400', 06:14:38 INFO - 'USER': 'cltbld', 06:14:38 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:38 INFO - '_': '/tools/buildbot/bin/python'} 06:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 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)) 06:14:38 INFO - Cleaning up... 06:14:38 INFO - Return code: 0 06:14:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:38 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 0x1e6d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, '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': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', '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 06:14:38 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 06:14:38 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 06:14:38 INFO - Using env: {'DISPLAY': ':0', 06:14:38 INFO - 'HOME': '/home/cltbld', 06:14:38 INFO - 'LANG': 'en_US.UTF-8', 06:14:38 INFO - 'LANGUAGE': 'en_US:en', 06:14:38 INFO - 'LOGNAME': 'cltbld', 06:14:38 INFO - 'MAIL': '/var/mail/cltbld', 06:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:38 INFO - 'MOZ_NO_REMOTE': '1', 06:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:38 INFO - 'NO_EM_RESTART': '1', 06:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:38 INFO - 'PWD': '/builds/slave/test', 06:14:38 INFO - 'SHELL': '/bin/bash', 06:14:38 INFO - 'SHLVL': '1', 06:14:38 INFO - 'TERM': 'linux', 06:14:38 INFO - 'TMOUT': '86400', 06:14:38 INFO - 'USER': 'cltbld', 06:14:38 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:38 INFO - '_': '/tools/buildbot/bin/python'} 06:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:14:38 INFO - Cleaning up... 06:14:38 INFO - Return code: 0 06:14:38 INFO - Running post-action listener: _resource_record_post_action 06:14:38 INFO - Running post-action listener: _start_resource_monitoring 06:14:38 INFO - Starting resource monitoring. 06:14:38 INFO - [mozharness: 2017-02-21 14:14:38.582681Z] Finished create-virtualenv step (success) 06:14:38 INFO - [mozharness: 2017-02-21 14:14:38.583194Z] Running install step. 06:14:38 INFO - Running pre-action listener: _resource_record_pre_action 06:14:38 INFO - Running main action method: install 06:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:14:38 INFO - Reading from file tmpfile_stdout 06:14:38 INFO - Detecting whether we're running mozinstall >=1.0... 06:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:14:38 INFO - Reading from file tmpfile_stdout 06:14:38 INFO - Output received: 06:14:38 INFO - Usage: mozinstall [options] installer 06:14:38 INFO - Options: 06:14:38 INFO - -h, --help show this help message and exit 06:14:38 INFO - -d DEST, --destination=DEST 06:14:38 INFO - Directory to install application into. [default: 06:14:38 INFO - "/builds/slave/test"] 06:14:38 INFO - --app=APP Application being installed. [default: firefox] 06:14:38 INFO - mkdir: /builds/slave/test/build/application 06:14:38 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'] 06:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:14:55 INFO - Reading from file tmpfile_stdout 06:14:55 INFO - Output received: 06:14:55 INFO - /builds/slave/test/build/application/firefox/firefox 06:14:55 INFO - Running post-action listener: _resource_record_post_action 06:14:55 INFO - [mozharness: 2017-02-21 14:14:55.489002Z] Finished install step (success) 06:14:55 INFO - [mozharness: 2017-02-21 14:14:55.489165Z] Running run-tests step. 06:14:55 INFO - Running pre-action listener: _resource_record_pre_action 06:14:55 INFO - Running main action method: run_tests 06:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:14:55 INFO - Python 2.7.3 06:14:55 INFO - Return code: 0 06:14:55 INFO - grabbing minidump binary from tooltool 06:14:55 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')]} 06:14:55 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 0x2108400>, '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 0x21093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2109840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:14:55 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 06:14:55 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 06:14:55 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 06:14:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:14:55 INFO - Return code: 0 06:14:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:14:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:14:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:14:55 INFO - ENV: RUST_BACKTRACE is now 1 06:14:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:14:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:14:55 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/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 06:14:55 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/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 06:14:55 INFO - Using env: {'DISPLAY': ':0', 06:14:55 INFO - 'HOME': '/home/cltbld', 06:14:55 INFO - 'LANG': 'en_US.UTF-8', 06:14:55 INFO - 'LANGUAGE': 'en_US:en', 06:14:55 INFO - 'LOGNAME': 'cltbld', 06:14:55 INFO - 'MAIL': '/var/mail/cltbld', 06:14:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:14:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:55 INFO - 'MOZ_NO_REMOTE': '1', 06:14:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:14:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:55 INFO - 'NO_EM_RESTART': '1', 06:14:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:55 INFO - 'PWD': '/builds/slave/test', 06:14:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:14:55 INFO - 'RUST_BACKTRACE': '1', 06:14:55 INFO - 'SHELL': '/bin/bash', 06:14:55 INFO - 'SHLVL': '1', 06:14:55 INFO - 'TERM': 'linux', 06:14:55 INFO - 'TMOUT': '86400', 06:14:55 INFO - 'USER': 'cltbld', 06:14:55 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606', 06:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:55 INFO - '_': '/tools/buildbot/bin/python'} 06:14:55 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/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 06:14:55 INFO - mozversion application_buildid: 20170221131600 06:14:55 INFO - mozversion application_changeset: b69d4316561aa125612509a202922455d2de03e5 06:14:55 INFO - mozversion application_display_name: Nightly 06:14:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:14:55 INFO - mozversion application_name: Firefox 06:14:55 INFO - mozversion application_remotingname: firefox 06:14:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:14:55 INFO - mozversion application_vendor: Mozilla 06:14:55 INFO - mozversion application_version: 54.0a1 06:14:55 INFO - mozversion platform_buildid: 20170221131600 06:14:55 INFO - mozversion platform_changeset: b69d4316561aa125612509a202922455d2de03e5 06:14:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:14:55 INFO - mozversion platform_version: 54.0a1 06:14:55 INFO - using testdate: 1487686495 06:14:55 INFO - actual date: 1487686495 06:14:55 INFO - starting webserver on 'localhost:35237' 06:14:55 INFO - SUITE-START | Running 2 tests 06:14:55 INFO - TEST-START | dromaeo_css 06:14:55 INFO - operating with platform_type : linux_ 06:14:55 INFO - Initialising browser for dromaeo_css test... 06:14:55 INFO - TEST-INFO | started process 27883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMffsus/profile http://localhost:35237/getInfo.html) 06:14:58 INFO - PROCESS | 27883 | Iterating over profile /tmp/tmpMffsus/profile 06:14:59 INFO - PROCESS | 27883 | __metrics Screen width/height:1600/1200 06:14:59 INFO - PROCESS | 27883 | colorDepth:24 06:14:59 INFO - PROCESS | 27883 | Browser inner width/height: 1024/697 06:14:59 INFO - PROCESS | 27883 | __metrics 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/compatibility.ini = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/permissions.sqlite = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/directoryLinks.json = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/extensions.json = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/user.js = Tue Feb 21 2017 06:14:55 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/bookmarkbackups = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/content-prefs.sqlite = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/places.sqlite-shm = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/webappsstore.sqlite = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/pluginreg.dat = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/safebrowsing-backup = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/localstore.rdf = Tue Feb 21 2017 06:14:12 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/cookies.sqlite = Tue Feb 21 2017 06:14:58 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/prefs.js = Tue Feb 21 2017 06:14:12 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/secmod.db = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | Stat failure 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/addons.json = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/.parentlock = Tue Feb 21 2017 06:14:55 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/webappsstore.sqlite-wal = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/minidumps = Tue Feb 21 2017 06:14:55 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/gmp = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/cert8.db = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/storage.sqlite = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/startupCache = Tue Feb 21 2017 06:14:55 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/sessionCheckpoints.json = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/frequencyCap.json = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/places.sqlite-wal = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/thumbnails = Tue Feb 21 2017 06:14:57 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | Stat failure 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/places.sqlite = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/safebrowsing = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/webappsstore.sqlite-shm = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/key3.db = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/extensions.ini = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/blocklist.xml = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/extensions = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/crashes = Tue Feb 21 2017 06:14:55 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/cache2 = Tue Feb 21 2017 06:14:56 GMT-0800 (PST) 06:14:59 INFO - PROCESS | 27883 | No birth date. Using mtime. 06:14:59 INFO - PROCESS | 27883 | Using date: /tmp/tmpMffsus/profile/mimeTypes.rdf = Tue Feb 21 2017 06:14:59 GMT-0800 (PST) 06:15:00 INFO - PROCESS | 27883 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:15:00 INFO - PROCESS | 27883 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:15:01 INFO - TEST-INFO | 27883: exit 0 06:15:01 INFO - Browser initialized. 06:15:01 INFO - Running cycle 1/1 for dromaeo_css test... 06:15:01 INFO - TEST-INFO | started process 27977 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMffsus/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:15:13 INFO - PROCESS | 27977 | RSS: Main: 143302656 06:15:13 INFO - PROCESS | 27977 | 06:18:14 INFO - PROCESS | 27977 | Cycle 1(1): loaded http://localhost:35237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35237/tests/dromaeo/cssquery-ext.html) 06:18:16 INFO - PROCESS | 27977 | RSS: Main: 356950016 06:18:16 INFO - PROCESS | 27977 | 06:20:02 INFO - PROCESS | 27977 | ************************* 06:20:02 INFO - PROCESS | 27977 | A coding exception was thrown and uncaught in a Task. 06:20:02 INFO - PROCESS | 27977 | 06:20:02 ERROR - PROCESS | 27977 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:20:02 INFO - PROCESS | 27977 | Full stack: 06:20:02 INFO - PROCESS | 27977 | ************************* 06:21:19 INFO - PROCESS | 27977 | Cycle 1(1): loaded http://localhost:35237/tests/dromaeo/cssquery-ext.html (next: http://localhost:35237/tests/dromaeo/cssquery-jquery.html) 06:21:20 INFO - PROCESS | 27977 | RSS: Main: 238981120 06:21:20 INFO - PROCESS | 27977 | 06:24:22 INFO - PROCESS | 27977 | Cycle 1(1): loaded http://localhost:35237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35237/tests/dromaeo/cssquery-mootools.html) 06:24:23 INFO - PROCESS | 27977 | RSS: Main: 182710272 06:24:23 INFO - PROCESS | 27977 | 06:27:25 INFO - PROCESS | 27977 | Cycle 1(1): loaded http://localhost:35237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35237/tests/dromaeo/cssquery-prototype.html) 06:27:25 INFO - PROCESS | 27977 | RSS: Main: 210722816 06:27:25 INFO - PROCESS | 27977 | 06:30:28 INFO - PROCESS | 27977 | Cycle 1(1): loaded http://localhost:35237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35237/tests/dromaeo/cssquery-yui.html) 06:30:29 INFO - PROCESS | 27977 | RSS: Main: 189947904 06:30:29 INFO - PROCESS | 27977 | 06:33:31 INFO - PROCESS | 27977 | Cycle 1(1): loaded http://localhost:35237/tests/dromaeo/cssquery-yui.html (next: http://localhost:35237/tests/dromaeo/cssquery-dojo.html) 06:33:32 INFO - PROCESS | 27977 | RSS: Main: 280915968 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | __start_tp_report 06:33:32 INFO - PROCESS | 27977 | _x_x_mozilla_page_load 06:33:32 INFO - PROCESS | 27977 | _x_x_mozilla_page_load_details 06:33:32 INFO - PROCESS | 27977 | |i|pagename|runs| 06:33:32 INFO - PROCESS | 27977 | |0;dojo.html;3122.5472306515558;3146.5280207968804;3147.6222853257605;3173.952390714141;3200.503921892106;5124.333836601246;5184.740762961856;5237.764300606473;5321.308229930109;5355.169948657955;5206.323177986866;5228.503292187236;5247.422783493822;5277.366715994077;5287.154055351145;4761.309610106541;4769.5707386335225;4857.077155340492;4920.532549407801;4949.975250123744;4918.458969513351;4949;4964.652474326798;4966.106100901836;4973.950260497385;4650.534946505356;4709.317149013408;4716.198246298103;4761.309610106515;4847.51524847516;4762.1428142934255;4763.69036012658;4772.355731976173;4776.283557466373;4797.208460603996;4790.233562629963;4816.868036011534;4826.251930950708;4864.975675121602;4868.464468908453;5229.450907654717;5243.711595862226;5274.868128296785;5310.8032577451;5325;5492.066348720727;5650.802221922254;5654.321481422215;5654.575906807006;5657.943420565783;5560.332760068777;5570.554355651579;5633.718314084279;5640.830775076754;5642.463965923231;5273.709945952971;5277.604179686539;5277.920831187535;5278.1818818082875;5280.841574752763;5687.431256874363;5722.113072473773;5768.798092066758;5838.182654428383;5861.6189947653265;5348.652337598121;5368.91946620801;5382.165764306539;5615.157726341;5626.915596266059;5519.475649813261;5531.640443371168;5533.861653458615;5536.197251398525;5538.0585300499015;5499.890002199933;5511.22842802;5511.834644960658;5514.862128446741;5517.69652669099;5598.300212473441;5602.1036634138345;5602.85992850182;5649.519790817826;5650.208970744099;4271.743695378287;4361.345798130305;4377.007288383366;4387.736735795863;4423.911521769611;3868.3617203161725;3885.747426417274;3892.9610703893163;3921.490206273166;3959.40608908666;5335.839924802262;5352.116900711339;5366.302380690485;5381.67709937405;5407.891842163135;5659.434056594308;5692.174634677948;5694.202811606378;5697.829065128053;5704.828855134269;5148.4851514848215;5176.948230517722;5249.291345668362;5368;5384.596155288369;4860.9513904861205;4911.189653707098;4918.5573298403315;4919.163742163841;4920.803167873245;5902.2622172228475;5906.2026626406605;5906.822795316148;5913.704314784243;5923.318818335861;5681.857953551194;5687.488126068673;5692.231548740867;5694.515966142924;5700.800471983461;5687.090065589486;5694.487496125203;5734.827955161185;5813.94186058134;5827;5456.781728730806;5499.202615620793;5499.780008799603;5500.477454641803;5502.972485137709;5601;5605.32736071674;5611.859703507445;5613.803516876889;5613.831585052455;5587.273654424899;5634.887302254014;5665.12190610456;5665.405132461197;5668.178114173505;5601.887962240814;5611.831645050655;5618.803341883015;5646.971765141148;5657.321121465286;5319.069162896555;5353.652012619167;5604.411536788742;5604.85987850307;5606.215129881902;5427.972860135674;5434.701091440009;5444.754986025718;5445.128779395278;5446.264754258124;5334.413214546475;5338.8932221356135;5348.197770334481;5354.678719276856;5355.625106242525 06:33:32 INFO - PROCESS | 27977 | |1;ext.html;591.3938213331335;594.0406243916077;598.2491972574419;598.2970010237971;600.4655856287904;40989.79505102474;43166.568334316646;43550;48236.276455853105;48321.27518087231;42752.57247427525;43180;43215.78392108043;43285.7835710821;43310;7403.851922961538;7592.696292148307;9120.407173533717;9244.583993720298;9315.254779617615;10323.84514232287;10413.114885234763;10520.842187367196;10677.305975111633;11223.270487418291;10973.23187376884;11026.944865275662;11029.503672334724;11030.518133419997;11039.116870650369;10334.689959301233;10388.53251603676;10400.271980961335;10417.114545263661;10427.322224055411;10389.42858142802;10461.9476902615;10462.738431539272;10464.162866970624;10475.371477711362;42144.57855421437;42336;42494.15011699749;42531.36202956958;42537;3860.613938606117;3890.7471014383973;3925.3523168677134;3925.4896863407575;4009.4787677601726;31134;31192.376152476823;31241.843790780902;31294;31816.045518634477;4159.480064991876;4265.466816647919;4274;4407.977960110211;4433.556644335573;310852;312004;312256;312663;312985;47414;48809.9463588511;48831.755841221275;59916.70041649851;60117.78586569552;57970;59545.404545953985;59598.40401596015;59607.70196148992;59621;15561;15771.526854194393;15775.211239437982;15787;15790.210489475481;11801.29192248468;11869.88130118705;11906.821397679041;12056.095792815398;12082.093842961813;16383.83616163847;16399.262033208535;16408.343666253455;16459.423920162975;16479.363301871883;6426.4537514311805;6435.871282574322;6515.804525864231;6563.573367731177;6621.9078447828115;862.6333808131519;865.4677373415304;888.3809902367551;909.4997751236793;916.3620426905176;27333;28752.71247287542;29092.709072909;29971.550426743615;30075.548866767014;963.5712108111823;1070.4915165296422;1082.9891701083045;1094.075506197262;1118.7315044389288;15075.066088084237;15277.694446111016;15336.156511391757;15687;15816.28826702801;337386;339307;339790;339899;341597;54820;54935.17597236045;55187.724061379435;55191.17213241805;55230.44769552333;1326.6617012661902;1349.84476785169;1354.4717560151548;1360.2314692198877;1386.0782579584666;26844.463110738066;27506.312342191606;27523.86238068877;28145.43709125847;28467.430651386443;46017;46579.767101165635;46687.53312466832;46735.76632116818;46745.766271169785;274562;275965;277592;277948;278388;31915.840420797747;34456.82771586126;35957.820210898775;38764.80617596894;38770;39017.414738778374;39364.409533856444;40877.182456350114;44517.4207459146;45388;34377.65622343744;35877.64122358847;35907.64092359043;36324.45513317355;36405;34599.65400345964;35546.822265888506;36052.27895442129;36190.63809361978;36344.636553634125;35822.641773581934;36617.633823661425;36633.63366366302;36823;36931;30525.694743052285;32416.675833241366;32435;32577.511337330434;32607.673923260463;24471.87764061168;25014.49971000606;33154.834225828716;35672.64327356797;35988.82005589955 06:33:32 INFO - PROCESS | 27977 | |2;jquery.html;396.69058134654944;401.39589917174646;408.4730697400354;408.48734837778585;421.3700517726;12660.556880509184;18649.25402983881;19142.71285930709;20397.184112635478;20411.26504247139;20533.589328213428;20845.27041553546;20971.685424718642;21530.784692153073;21752;5891.322497912752;6059.303180134297;6099.847503812418;6108.938910610904;6117.724702388403;5043.697378157322;5927.110933360009;6018.097285407202;6021.096835474692;6690.83272918176;6836.521443498957;6836.931630683679;6842.897356539655;6844.9657751711175;6849.280825513324;5864.179014937912;6092.177556029924;6137.355577664371;6253.843653908643;6833.350831670983;5325.121354976424;6083.878322433527;6088.66512341821;6093.238345206849;6098.786542470993;14173.574792756233;14753.926230368887;15124.243787810676;15135.848641513554;15167.469138580096;2593.727658595858;2620.174644986823;2637.709851916288;2640.2475294540964;2681.503921774478;10592.775163691556;10669.946650266776;10899.237053406265;11021.944890275498;12942.417591208417;1815.3464752689022;1843.4746097362322;1862.134107639954;1873.840723538501;1875.859310551714;88611.82210846298;90613.54693226557;90622.54688726514;91237.54381228118;91475.54262228713;11692.479977602858;11924.642260732191;11958.64124076279;12065.638030859089;12335.074869384833;13905.79141312881;14478.855211447752;17177;17230.310787568607;17264;7428.888566671504;7764.301212890867;7946;8034.276915077554;8114.5942702865805;7434.1079070510805;7524.811879703051;7703.730369437043;7705.267999540035;7731.188225236382;5039.294498770175;5260.658057226268;5273.762680679379;5331.3868905076415;5416.241726158341;1974.8222659960672;2026.4528577284345;2038.541328201143;2045.719023289022;2048.795120487939;866.9046404895456;883.0948278014957;892.7991201979503;895.0735988252128;913.917747402737;19179.80820191808;19218;19941.700874486894;20103.095360708805;20180.39458816238;865.1175800683268;868.4268382867277;890.8218356328761;891.6567121658079;896.7444278380631;10607.84088238677;10793.78412431747;10894.455277236106;11053;11085.168612354106;561332;570831;583261;608006;609309;94355;95806;97574.51212743891;97756;99160;976.2092704909046;1011.049613363436;1030.469308306223;1033.8811036730722;1034.9689509314733;16151.85109592248;16599.668006639557;16875.240614172148;17168.914155429142;17284.222210000833;44669;44693.32960005668;44755.32867007062;44858.77570612126;46160.53839461697;59791.40208597858;60553.69723151356;60946;61338;62732.37267627265;6847.965760171366;8447.113053129333;8522.70170544028;8584.871226931476;8964.3276754245;13180.340982951006;13222.603321900358;13328.533501327407;13551.457941682222;13756.243406612732;11177.329360238411;11379.772404551697;11386.54453821871;11660.825087623523;11722.765544688888;11158.44207789591;11199.328040317607;11449.198556100993;11790.23363481371;11955.222910510789;10980.835287470534;11295.943520282346;11963.880361196276;11971;12145.392730363623;8631.91368086311;8710.956445217987;8741.431806932529;8769.166929141722;8973.416727912665;10618.628348007782;11489.482973266391;11530.654080377602;11648.242864213798;11916.463759131033 06:33:32 INFO - PROCESS | 27977 | |3;mootools.html;1420.9573712788617;1425.4796999095333;1428.9356978935941;1433.3549902543857;1438.6331485471208;8179.836403271938;8621.05168431472;8625.482471051733;8631.48211107334;8634.395592308541;8337.95831020844;8536.402451828359;8553.52955587444;8567.271781898531;8737.781555461117;2214.977850221493;2249.156566287642;2255.7744225577394;2257.3340864445026;2257.8758168300737;1868.9439316820517;2206.8013878750908;2238.1942500699734;2245.38251980705;2246.8428759189032;1938.4087853204767;2152.7309086364203;2209.569134018867;2216.1467834883524;2233.519793244452;2172.2180015194517;2200.5378870437225;2206.5035367042447;2208.138825857917;2208.977910220893;2169.110664627511;2213.9778602213933;2218.9334319970426;2220.3449982255274;2221.144859229192;8668.826623467496;8731.650733970633;8732.257758090569;8737.737867863974;8740.825183496358;4901.5588597026435;5018.172001619729;5023.34696489454;5028.522290382408;5032.295478632995;2869.153599688097;3102.0848849589424;3163.9367212655616;3175.952360714568;3179.1257404213793;1255.1778585026823;1304.3804193008245;1306.8497122830806;1354.1671871798872;1361.6255529729306;11747.060235181165;11846.407679615984;11857.703557411134;11896.88103118975;11902.702432439171;4014.1168942833115;4277.657787377002;4319.503257125408;4320.978395108067;4354.216241076637;9401.905980940102;9423.670171543963;9426.481543515172;9442.055794420641;9471.337006409485;5047.141985862412;5058.898822023612;5059.544640982329;5081.8729531762;5089.720065396439;4521.412216411845;4555.2483840166005;4649.883752906205;4680.882977925579;4688.5545873142;3497.405441075023;3525.806080665588;3564.322778672044;3579.2125732338845;3581.910452238663;1082.4587706146926;1084.4252546150553;1092.6721983404946;1096.2271598523023;1098.7857367813199;726.6294189963157;746.1493896957472;773.377431167904;780.3055280800093;855.8074433252593;5363.517283444508;5469.808556700496;5582.2694964561115;5597.656562424989;5691.121929763168;979.9461029643295;1013.9188864890791;1022.243539780572;1024.9077583017565;1027.609508386823;3529.7176225901867;3725.2177042820767;3873.1479074603544;3906.140649057203;4024.5388965489656;10204.38773673582;10436.686899393031;10508.947455262674;10512.894871051345;10519.368837869752;9808.656697015713;10235.130013948748;10465.372077675363;10466.162706983423;10473.266871319087;7917.2874441300555;8138.959305203436;8139.674413023531;8158.673653053811;8163.755087347389;5303.708296043755;5404.216388623706;5564.137558678412;5588.077967135378;5801.1878337032;9078.54607269647;9380.859287110563;9393.24854011691;9543.33196676226;9659.9034009659;9258.35191536586;9528.904710952802;9624.37441566296;9646;9797.020297970146;7274.30894065063;7324;7333.156686980852;7396.741114060982;7403;8115.715949941724;8194.426390152632;8196.180381961756;8229.300509456763;8254.0507841599;7104.786856394317;7159.212486626571;7283.817904552428;7361.484696071224;7523.586202758901;6937.583744975318;7203.387712044535;7340.853182936418;7360.558366498028;7479.626018698935;6568.934310656832;6777.220619628806;6777.322267773183;6777.8983315249325;6782.864342713019;4992.500749924979;5062.063518249136;5082.567981721447;5148.716820574904;5281.10221262394;4792.257200133985;4832.081904438145;4885.340479035427;5158.200478925773;5231.372235331784 06:33:32 INFO - PROCESS | 27977 | |4;prototype.html;395.34048945512825;396.2095619239616;414.97095203335766;416.9353750168723;421.03792833375735;5219.817306394277;5225.686458812469;5258.789648414068;5273.18265668821;5321.707306098173;5064.924026139611;5192.480751924805;5217.165253559431;5243.4232234454175;5256.474352564742;4200.180964711883;4201.39079833424;4203.495580530325;4214.810333534998;4215.346621273692;4209.621134097931;4210.179015092058;4214.97892510537;4222.282212023963;4234.555371685975;3993.960060399402;4078.143589846136;4132.834686612532;4189.3087640539425;4192.7484350938885;4085.7344272622186;4098.73358231717;4113.794310284474;4128.422020917089;4134.4831896013;3622.873199438033;3626.582942961567;3634.8727794527063;3635.4728564358284;3663.816809159531;4870.683405578661;5146.974265128688;5147.253648221024;5152.175651895679;5159.9422118466;4717.198076327033;4739.431268247798;4743.810247590092;4753.47711751707;4772.475027746945;5528.170774383834;5542.39033706292;5545.3622833374275;5545.445455454463;5546;3818.5417749869916;4028.979855100735;4035.3341698619693;4046.3121269384274;4049.7570145791346;5482.945170548283;5580.553555715533;5653.530082178622;5655.37790843011;5691.544676425876;4048.1903619276263;4056.4320995060716;4058.1680755445063;4122.690798190148;4126.896827579335;4182.832686692498;4183.267928112568;4186.958130418657;4189.371594260885;4191.8532851350055;4441.800118994653;4464.620507256855;4572.4055872737;4677.929831052537;4698.506656800987;5427.755750991215;5429.239906413105;5468.125099983983;5477.260569823103;5485.698286594195;1358.7282543491342;1381.9861801381858;1391.8816900563565;1393.6794537256487;1396.2250950722446;937.381328323317;943.2642538819731;957.8132264208413;980.0150848397315;997.1424574865609;5126.2054381570915;5142.8971420572125;5154.639175257696;5167.302414174113;5175.870603234949;5484.945150548523;5484.972575137099;5485.286912701324;5486.91769623456;5490.533304669148;4956.975215123901;5158.303629010035;5165.405978312467;5273.6572122811895;5314;2870.2250392394353;2887.1627228104035;2905.2155917902046;2918.1683220282116;2922.240217543454;5689.715514224355;5701.4298570142655;5711.657300561979;5714;5722.170285308607;5456.427075157131;5754.021816291241;5760.884782304414;5763.394843541451;5790.71046447676;5890.823275301748;5894.823155305347;5903.350631430453;5909.690573278977;5913.05391137416;5531.612787104864;5651.773929042793;5676.545876329967;5685.759117303098;5698.629589076697;5570.2201691762375;5574.749136288796;5581.162825576196;5642.586235327732;5655.688937108499;5634.6337488063145;5752.654840709571;5768.53851691872;5770.662881532986;5865.501432378295;3839.6928245739714;4089.8568550100604;4097.405876148002;4145.419641250288;4157.625813676663;5623.578231632726;5651.547876169814;5673.914891276717;5685.374608793112;5692.743826527733;5278.627556835174;5479.726013699379;5511.311086114236;5529;5549;5544.417836127149;5566.91649625264;5572.442755724395;5580.298009107261;5586.441355864544;5311.150215965427;5540.279763630702;5548.140038294071;5552.639078459887;5562.805301814417;5557.8610534735335;5561.304836895388;5567.66594004361;5599.468050535193;5600.467955544217;5416.458354164552;5425.348958125051;5425.376081750649;5433.56531477473;5444.455554444524 06:33:32 INFO - PROCESS | 27977 | |5;yui.html;767.9424043196761;793.9166387529309;837.6440012994477;843.1147295339891;845.8688903220003;15198.772018419719;16392.426265080725;16394.26225819838;16416.671666566675;16526;15344.846551534481;15530.30113644886;15535.223238838069;15559.688806223898;15562.144082075481;771.1055175995846;785.0696924144878;788.6293442082241;790.7113903425237;805.1586092533288;794.2335646101511;797.696875187428;801.0867610923552;802.7471346525825;803.0644299391203;833.4249367936135;833.6082041440544;835.4319063037133;835.8871552340418;835.9456635318716;826.3389288569181;826.4917075998278;827.5986146718836;828.6354004238146;829.8755186721979;605.3613437823095;609.6342194683199;611.6299638718556;612.4549151255386;614.1248720573166;15973;15983.840161598468;15988.200589970571;16086;16695.83304166967;14022.368993395321;14346.928265358607;14358.69830684193;14368.928155359261;14561.126332419983;12243.32661703606;12279.263244205287;12331;12554.435050422662;12584;3637.672249628893;3691.741578089535;3721.106934335767;3724.683401910841;3726.8136593170234;66957;71661;72340.63829680817;72976.6351168246;76337;10125.291229613857;10226.335288206243;10325.122364599094;10779.784404312026;11062.336259824437;17947.551311217325;18054.729179062324;18059.187336569885;18066.458006259832;18130.818691813176;7238.384737297283;7248.316784208756;7337.266273372727;7479;7625.504342217738;4226.72892466018;4314.072474418015;4343.066240758252;4401.440906395939;4415.27148020573;3303.7687361884437;3387.2040070583394;3393.9151521211675;3396.677315655009;3401.523786669868;581.1428143488405;604.5133667397699;632.5192853431425;637.6174295422802;642.210081599635;692.736760031195;712.1489819665553;712.803978905805;732.3010615372391;737.9483436159525;9179.77050573741;9467.579863020603;9540.379875308083;10261.127804136582;10504.789904201873;717.3185473799911;748.4648476339444;753.9811504712426;763.522798251093;779.4193325972074;4215.346621273708;4550.567696068868;4578.908421831545;4580.770961451981;4619.376384188092;72620.63689681518;73948.63025684837;75844.24155758372;75999;77095;66922.33077669259;68159;68627;68674.3132568678;69177.30822691809;60768.696156518934;61085.11319094551;61449.38550614437;61980;62092;10122.79754404893;10518.316309439862;10591.89408105909;10784.23043671632;10838.403887786248;37302.44046339253;38303;38495.422568662056;39168;43362.56637433711;50011.74947127315;53326.73336633292;57595.424045759006;57867;59188;13723.382447790073;13759.862401375858;13801;14243.928780356031;14436.711265774837;15278.923605382346;15419.922900385427;15567.532974010797;15767.053976761432;15965.760513592537;13769;13935.163890166623;14142.92928535392;14358.928205358907;14864.85135148678;12807.487700491876;13389.732205356033;13390.93304533471;13839.584812455643;14031.579052628438;12873.613791586267;13342.666433339244;13421.597352079454;13528.391222395212;13596.388162532907;9371.437713737198;9560.76098097553;9604;9760.658376956773;9933.106020458194;10202.540885660015;10445.68662940113;11017.504212310625;11311.717207069889;11692.532298707956 06:33:32 INFO - PROCESS | 27977 | __end_tp_report 06:33:32 INFO - PROCESS | 27977 | __start_cc_report 06:33:32 INFO - PROCESS | 27977 | _x_x_mozilla_cycle_collect,2459 06:33:32 INFO - PROCESS | 27977 | __end_cc_report 06:33:32 INFO - PROCESS | 27977 | __startTimestamp1487687612220__endTimestamp 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | ------- Summary: start ------- 06:33:32 INFO - PROCESS | 27977 | Number of tests: 6 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | [#0] dojo.html Cycles:165 Average:5242.61 Median:5382.17 stddev:569.50 (10.6%) stddev-sans-first:546.43 06:33:32 INFO - PROCESS | 27977 | Values: 3122.5 3146.5 3147.6 3174.0 3200.5 5124.3 5184.7 5237.8 5321.3 5355.2 5206.3 5228.5 5247.4 5277.4 5287.2 4761.3 4769.6 4857.1 4920.5 4950.0 4918.5 4949.0 4964.7 4966.1 4974.0 4650.5 4709.3 4716.2 4761.3 4847.5 4762.1 4763.7 4772.4 4776.3 4797.2 4790.2 4816.9 4826.3 4865.0 4868.5 5229.5 5243.7 5274.9 5310.8 5325.0 5492.1 5650.8 5654.3 5654.6 5657.9 5560.3 5570.6 5633.7 5640.8 5642.5 5273.7 5277.6 5277.9 5278.2 5280.8 5687.4 5722.1 5768.8 5838.2 5861.6 5348.7 5368.9 5382.2 5615.2 5626.9 5519.5 5531.6 5533.9 5536.2 5538.1 5499.9 5511.2 5511.8 5514.9 5517.7 5598.3 5602.1 5602.9 5649.5 5650.2 4271.7 4361.3 4377.0 4387.7 4423.9 3868.4 3885.7 3893.0 3921.5 3959.4 5335.8 5352.1 5366.3 5381.7 5407.9 5659.4 5692.2 5694.2 5697.8 5704.8 5148.5 5176.9 5249.3 5368.0 5384.6 4861.0 4911.2 4918.6 4919.2 4920.8 5902.3 5906.2 5906.8 5913.7 5923.3 5681.9 5687.5 5692.2 5694.5 5700.8 5687.1 5694.5 5734.8 5813.9 5827.0 5456.8 5499.2 5499.8 5500.5 5503.0 5601.0 5605.3 5611.9 5613.8 5613.8 5587.3 5634.9 5665.1 5665.4 5668.2 5601.9 5611.8 5618.8 5647.0 5657.3 5319.1 5353.7 5604.4 5604.9 5606.2 5428.0 5434.7 5444.8 5445.1 5446.3 5334.4 5338.9 5348.2 5354.7 5355.6 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | [#1] ext.html Cycles:180 Average:48309.31 Median:35073.24 stddev:81144.30 (231.4%) stddev-sans-first:81292.84 06:33:32 INFO - PROCESS | 27977 | Values: 591.4 594.0 598.2 598.3 600.5 40989.8 43166.6 43550.0 48236.3 48321.3 42752.6 43180.0 43215.8 43285.8 43310.0 7403.9 7592.7 9120.4 9244.6 9315.3 10323.8 10413.1 10520.8 10677.3 11223.3 10973.2 11026.9 11029.5 11030.5 11039.1 10334.7 10388.5 10400.3 10417.1 10427.3 10389.4 10461.9 10462.7 10464.2 10475.4 42144.6 42336.0 42494.2 42531.4 42537.0 3860.6 3890.7 3925.4 3925.5 4009.5 31134.0 31192.4 31241.8 31294.0 31816.0 4159.5 4265.5 4274.0 4408.0 4433.6 310852.0 312004.0 312256.0 312663.0 312985.0 47414.0 48809.9 48831.8 59916.7 60117.8 57970.0 59545.4 59598.4 59607.7 59621.0 15561.0 15771.5 15775.2 15787.0 15790.2 11801.3 11869.9 11906.8 12056.1 12082.1 16383.8 16399.3 16408.3 16459.4 16479.4 6426.5 6435.9 6515.8 6563.6 6621.9 862.6 865.5 888.4 909.5 916.4 27333.0 28752.7 29092.7 29971.6 30075.5 963.6 1070.5 1083.0 1094.1 1118.7 15075.1 15277.7 15336.2 15687.0 15816.3 337386.0 339307.0 339790.0 339899.0 341597.0 54820.0 54935.2 55187.7 55191.2 55230.4 1326.7 1349.8 1354.5 1360.2 1386.1 26844.5 27506.3 27523.9 28145.4 28467.4 46017.0 46579.8 46687.5 46735.8 46745.8 274562.0 275965.0 277592.0 277948.0 278388.0 31915.8 34456.8 35957.8 38764.8 38770.0 39017.4 39364.4 40877.2 44517.4 45388.0 34377.7 35877.6 35907.6 36324.5 36405.0 34599.7 35546.8 36052.3 36190.6 36344.6 35822.6 36617.6 36633.6 36823.0 36931.0 30525.7 32416.7 32435.0 32577.5 32607.7 24471.9 25014.5 33154.8 35672.6 35988.8 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | [#2] jquery.html Cycles:180 Average:32194.46 Median:404.93 stddev:96589.11 (23853.0%) stddev-sans-first:96830.55 06:33:32 INFO - PROCESS | 27977 | Values: 396.7 401.4 408.5 408.5 421.4 12660.6 18649.3 19142.7 20397.2 20411.3 20533.6 20845.3 20971.7 21530.8 21752.0 5891.3 6059.3 6099.8 6108.9 6117.7 5043.7 5927.1 6018.1 6021.1 6690.8 6836.5 6836.9 6842.9 6845.0 6849.3 5864.2 6092.2 6137.4 6253.8 6833.4 5325.1 6083.9 6088.7 6093.2 6098.8 14173.6 14753.9 15124.2 15135.8 15167.5 2593.7 2620.2 2637.7 2640.2 2681.5 10592.8 10669.9 10899.2 11021.9 12942.4 1815.3 1843.5 1862.1 1873.8 1875.9 88611.8 90613.5 90622.5 91237.5 91475.5 11692.5 11924.6 11958.6 12065.6 12335.1 13905.8 14478.9 17177.0 17230.3 17264.0 7428.9 7764.3 7946.0 8034.3 8114.6 7434.1 7524.8 7703.7 7705.3 7731.2 5039.3 5260.7 5273.8 5331.4 5416.2 1974.8 2026.5 2038.5 2045.7 2048.8 866.9 883.1 892.8 895.1 913.9 19179.8 19218.0 19941.7 20103.1 20180.4 865.1 868.4 890.8 891.7 896.7 10607.8 10793.8 10894.5 11053.0 11085.2 561332.0 570831.0 583261.0 608006.0 609309.0 94355.0 95806.0 97574.5 97756.0 99160.0 976.2 1011.0 1030.5 1033.9 1035.0 16151.9 16599.7 16875.2 17168.9 17284.2 44669.0 44693.3 44755.3 44858.8 46160.5 59791.4 60553.7 60946.0 61338.0 62732.4 6848.0 8447.1 8522.7 8584.9 8964.3 13180.3 13222.6 13328.5 13551.5 13756.2 11177.3 11379.8 11386.5 11660.8 11722.8 11158.4 11199.3 11449.2 11790.2 11955.2 10980.8 11295.9 11963.9 11971.0 12145.4 8631.9 8711.0 8741.4 8769.2 8973.4 10618.6 11489.5 11530.7 11648.2 11916.5 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | [#3] mootools.html Cycles:180 Average:5524.96 Median:5030.41 stddev:3152.76 (62.7%) stddev-sans-first:3146.52 06:33:32 INFO - PROCESS | 27977 | Values: 1421.0 1425.5 1428.9 1433.4 1438.6 8179.8 8621.1 8625.5 8631.5 8634.4 8338.0 8536.4 8553.5 8567.3 8737.8 2215.0 2249.2 2255.8 2257.3 2257.9 1868.9 2206.8 2238.2 2245.4 2246.8 1938.4 2152.7 2209.6 2216.1 2233.5 2172.2 2200.5 2206.5 2208.1 2209.0 2169.1 2214.0 2218.9 2220.3 2221.1 8668.8 8731.7 8732.3 8737.7 8740.8 4901.6 5018.2 5023.3 5028.5 5032.3 2869.2 3102.1 3163.9 3176.0 3179.1 1255.2 1304.4 1306.8 1354.2 1361.6 11747.1 11846.4 11857.7 11896.9 11902.7 4014.1 4277.7 4319.5 4321.0 4354.2 9401.9 9423.7 9426.5 9442.1 9471.3 5047.1 5058.9 5059.5 5081.9 5089.7 4521.4 4555.2 4649.9 4680.9 4688.6 3497.4 3525.8 3564.3 3579.2 3581.9 1082.5 1084.4 1092.7 1096.2 1098.8 726.6 746.1 773.4 780.3 855.8 5363.5 5469.8 5582.3 5597.7 5691.1 979.9 1013.9 1022.2 1024.9 1027.6 3529.7 3725.2 3873.1 3906.1 4024.5 10204.4 10436.7 10508.9 10512.9 10519.4 9808.7 10235.1 10465.4 10466.2 10473.3 7917.3 8139.0 8139.7 8158.7 8163.8 5303.7 5404.2 5564.1 5588.1 5801.2 9078.5 9380.9 9393.2 9543.3 9659.9 9258.4 9528.9 9624.4 9646.0 9797.0 7274.3 7324.0 7333.2 7396.7 7403.0 8115.7 8194.4 8196.2 8229.3 8254.1 7104.8 7159.2 7283.8 7361.5 7523.6 6937.6 7203.4 7340.9 7360.6 7479.6 6568.9 6777.2 6777.3 6777.9 6782.9 4992.5 5062.1 5082.6 5148.7 5281.1 4792.3 4832.1 4885.3 5158.2 5231.4 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | [#4] prototype.html Cycles:180 Average:4642.05 Median:5265.99 stddev:1346.26 (25.6%) stddev-sans-first:1311.76 06:33:32 INFO - PROCESS | 27977 | Values: 395.3 396.2 415.0 416.9 421.0 5219.8 5225.7 5258.8 5273.2 5321.7 5064.9 5192.5 5217.2 5243.4 5256.5 4200.2 4201.4 4203.5 4214.8 4215.3 4209.6 4210.2 4215.0 4222.3 4234.6 3994.0 4078.1 4132.8 4189.3 4192.7 4085.7 4098.7 4113.8 4128.4 4134.5 3622.9 3626.6 3634.9 3635.5 3663.8 4870.7 5147.0 5147.3 5152.2 5159.9 4717.2 4739.4 4743.8 4753.5 4772.5 5528.2 5542.4 5545.4 5545.4 5546.0 3818.5 4029.0 4035.3 4046.3 4049.8 5482.9 5580.6 5653.5 5655.4 5691.5 4048.2 4056.4 4058.2 4122.7 4126.9 4182.8 4183.3 4187.0 4189.4 4191.9 4441.8 4464.6 4572.4 4677.9 4698.5 5427.8 5429.2 5468.1 5477.3 5485.7 1358.7 1382.0 1391.9 1393.7 1396.2 937.4 943.3 957.8 980.0 997.1 5126.2 5142.9 5154.6 5167.3 5175.9 5484.9 5485.0 5485.3 5486.9 5490.5 4957.0 5158.3 5165.4 5273.7 5314.0 2870.2 2887.2 2905.2 2918.2 2922.2 5689.7 5701.4 5711.7 5714.0 5722.2 5456.4 5754.0 5760.9 5763.4 5790.7 5890.8 5894.8 5903.4 5909.7 5913.1 5531.6 5651.8 5676.5 5685.8 5698.6 5570.2 5574.7 5581.2 5642.6 5655.7 5634.6 5752.7 5768.5 5770.7 5865.5 3839.7 4089.9 4097.4 4145.4 4157.6 5623.6 5651.5 5673.9 5685.4 5692.7 5278.6 5479.7 5511.3 5529.0 5549.0 5544.4 5566.9 5572.4 5580.3 5586.4 5311.2 5540.3 5548.1 5552.6 5562.8 5557.9 5561.3 5567.7 5599.5 5600.5 5416.5 5425.3 5425.4 5433.6 5444.5 06:33:32 INFO - PROCESS | 27977 | 06:33:32 INFO - PROCESS | 27977 | [#5] yui.html Cycles:180 Average:17129.55 Median:4372.25 stddev:21459.81 (490.8%) stddev-sans-first:21484.84 06:33:32 INFO - PROCESS | 27977 | Values: 767.9 793.9 837.6 843.1 845.9 15198.8 16392.4 16394.3 16416.7 16526.0 15344.8 15530.3 15535.2 15559.7 15562.1 771.1 785.1 788.6 790.7 805.2 794.2 797.7 801.1 802.7 803.1 833.4 833.6 835.4 835.9 835.9 826.3 826.5 827.6 828.6 829.9 605.4 609.6 611.6 612.5 614.1 15973.0 15983.8 15988.2 16086.0 16695.8 14022.4 14346.9 14358.7 14368.9 14561.1 12243.3 12279.3 12331.0 12554.4 12584.0 3637.7 3691.7 3721.1 3724.7 3726.8 66957.0 71661.0 72340.6 72976.6 76337.0 10125.3 10226.3 10325.1 10779.8 11062.3 17947.6 18054.7 18059.2 18066.5 18130.8 7238.4 7248.3 7337.3 7479.0 7625.5 4226.7 4314.1 4343.1 4401.4 4415.3 3303.8 3387.2 3393.9 3396.7 3401.5 581.1 604.5 632.5 637.6 642.2 692.7 712.1 712.8 732.3 737.9 9179.8 9467.6 9540.4 10261.1 10504.8 717.3 748.5 754.0 763.5 779.4 4215.3 4550.6 4578.9 4580.8 4619.4 72620.6 73948.6 75844.2 75999.0 77095.0 66922.3 68159.0 68627.0 68674.3 69177.3 60768.7 61085.1 61449.4 61980.0 62092.0 10122.8 10518.3 10591.9 10784.2 10838.4 37302.4 38303.0 38495.4 39168.0 43362.6 50011.7 53326.7 57595.4 57867.0 59188.0 13723.4 13759.9 13801.0 14243.9 14436.7 15278.9 15419.9 15567.5 15767.1 15965.8 13769.0 13935.2 14142.9 14358.9 14864.9 12807.5 13389.7 13390.9 13839.6 14031.6 12873.6 13342.7 13421.6 13528.4 13596.4 9371.4 9560.8 9604.0 9760.7 9933.1 10202.5 10445.7 11017.5 11311.7 11692.5 06:33:32 INFO - PROCESS | 27977 | -------- Summary: end -------- 06:33:32 INFO - PROCESS | 27977 | 06:33:33 INFO - TEST-INFO | 27977: exit 0 06:33:33 INFO - TEST-OK | dromaeo_css | took 1117332ms 06:33:33 INFO - TEST-START | kraken 06:33:33 INFO - operating with platform_type : linux_ 06:33:33 INFO - Initialising browser for kraken test... 06:33:33 INFO - TEST-INFO | started process 28306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkttMgC/profile http://localhost:35237/getInfo.html) 06:33:35 INFO - PROCESS | 28306 | Iterating over profile /tmp/tmpkttMgC/profile 06:33:36 INFO - PROCESS | 28306 | __metrics Screen width/height:1600/1200 06:33:36 INFO - PROCESS | 28306 | colorDepth:24 06:33:36 INFO - PROCESS | 28306 | Browser inner width/height: 1024/697 06:33:36 INFO - PROCESS | 28306 | __metrics 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/compatibility.ini = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/permissions.sqlite = Tue Feb 21 2017 06:33:34 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/directoryLinks.json = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/extensions.json = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/user.js = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/bookmarkbackups = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/content-prefs.sqlite = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/places.sqlite-shm = Tue Feb 21 2017 06:33:34 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/webappsstore.sqlite = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/pluginreg.dat = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/safebrowsing-backup = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/localstore.rdf = Tue Feb 21 2017 06:14:12 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/cookies.sqlite = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/prefs.js = Tue Feb 21 2017 06:14:12 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/secmod.db = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | Stat failure 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/addons.json = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/.parentlock = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/webappsstore.sqlite-wal = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/minidumps = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/gmp = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/cert8.db = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/storage.sqlite = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/startupCache = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/sessionCheckpoints.json = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/frequencyCap.json = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/places.sqlite-wal = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/thumbnails = Tue Feb 21 2017 06:33:35 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | Stat failure 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/places.sqlite = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/safebrowsing = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/webappsstore.sqlite-shm = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/key3.db = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:36 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:36 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/extensions.ini = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:37 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:37 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/blocklist.xml = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:37 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:37 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/extensions = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:37 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:37 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/crashes = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:37 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:37 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/cache2 = Tue Feb 21 2017 06:33:33 GMT-0800 (PST) 06:33:37 INFO - PROCESS | 28306 | No birth date. Using mtime. 06:33:37 INFO - PROCESS | 28306 | Using date: /tmp/tmpkttMgC/profile/mimeTypes.rdf = Tue Feb 21 2017 06:33:36 GMT-0800 (PST) 06:33:37 INFO - PROCESS | 28306 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:33:37 INFO - PROCESS | 28306 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:33:38 INFO - TEST-INFO | 28306: exit 0 06:33:38 INFO - Browser initialized. 06:33:38 INFO - Running cycle 1/1 for kraken test... 06:33:38 INFO - TEST-INFO | started process 28396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkttMgC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:33:50 INFO - PROCESS | 28396 | RSS: Main: 142745600 06:33:50 INFO - PROCESS | 28396 | 06:33:52 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35237/tests/kraken/driver.html?testName=audio-beat-detection) 06:33:53 INFO - PROCESS | 28396 | RSS: Main: 199053312 06:33:53 INFO - PROCESS | 28396 | 06:33:56 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35237/tests/kraken/driver.html?testName=audio-dft) 06:33:57 INFO - PROCESS | 28396 | RSS: Main: 177381376 06:33:57 INFO - PROCESS | 28396 | 06:33:59 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35237/tests/kraken/driver.html?testName=audio-fft) 06:34:00 INFO - PROCESS | 28396 | RSS: Main: 259616768 06:34:00 INFO - PROCESS | 28396 | 06:34:02 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35237/tests/kraken/driver.html?testName=audio-oscillator) 06:34:03 INFO - PROCESS | 28396 | RSS: Main: 171462656 06:34:03 INFO - PROCESS | 28396 | 06:34:05 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:34:05 INFO - PROCESS | 28396 | RSS: Main: 174526464 06:34:05 INFO - PROCESS | 28396 | 06:34:08 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35237/tests/kraken/driver.html?testName=imaging-darkroom) 06:34:09 INFO - PROCESS | 28396 | RSS: Main: 213864448 06:34:09 INFO - PROCESS | 28396 | 06:34:12 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35237/tests/kraken/driver.html?testName=imaging-desaturate) 06:34:13 INFO - PROCESS | 28396 | RSS: Main: 213499904 06:34:13 INFO - PROCESS | 28396 | 06:34:16 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35237/tests/kraken/driver.html?testName=json-parse-financial) 06:34:16 INFO - PROCESS | 28396 | RSS: Main: 212340736 06:34:16 INFO - PROCESS | 28396 | 06:34:18 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:34:18 INFO - PROCESS | 28396 | RSS: Main: 174493696 06:34:18 INFO - PROCESS | 28396 | 06:34:20 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:34:21 INFO - PROCESS | 28396 | RSS: Main: 182149120 06:34:21 INFO - PROCESS | 28396 | 06:34:23 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:34:24 INFO - PROCESS | 28396 | RSS: Main: 189685760 06:34:24 INFO - PROCESS | 28396 | 06:34:26 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:34:27 INFO - PROCESS | 28396 | RSS: Main: 199327744 06:34:27 INFO - PROCESS | 28396 | 06:34:30 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:34:30 INFO - PROCESS | 28396 | RSS: Main: 196243456 06:34:30 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | Cycle 1(1): loaded http://localhost:35237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35237/tests/kraken/driver.html?testName=ai-astar) 06:34:32 INFO - PROCESS | 28396 | RSS: Main: 247640064 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | __start_tp_report 06:34:32 INFO - PROCESS | 28396 | _x_x_mozilla_page_load 06:34:32 INFO - PROCESS | 28396 | _x_x_mozilla_page_load_details 06:34:32 INFO - PROCESS | 28396 | |i|pagename|runs| 06:34:32 INFO - PROCESS | 28396 | |0;ai-astar;105;113;113;114;115;113;113;113;112;113 06:34:32 INFO - PROCESS | 28396 | |1;audio-beat-detection;152;123;121;167;119;119;119;118;143;118 06:34:32 INFO - PROCESS | 28396 | |2;audio-dft;161;161;162;160;163;161;161;163;161;162 06:34:32 INFO - PROCESS | 28396 | |3;audio-fft;75;78;76;104;78;76;76;77;91;75 06:34:32 INFO - PROCESS | 28396 | |4;audio-oscillator;84;83;110;84;84;84;104;83;90;114 06:34:32 INFO - PROCESS | 28396 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 06:34:32 INFO - PROCESS | 28396 | |6;imaging-darkroom;147;148;146;147;148;148;149;148;147;147 06:34:32 INFO - PROCESS | 28396 | |7;imaging-desaturate;95;95;95;95;96;96;96;96;96;95 06:34:32 INFO - PROCESS | 28396 | |8;json-parse-financial;56;56;56;56;62;57;56;57;57;57 06:34:32 INFO - PROCESS | 28396 | |9;json-stringify-tinderbox;64;64;64;64;65;64;65;64;65;66 06:34:32 INFO - PROCESS | 28396 | |10;stanford-crypto-aes;84;87;92;83;84;84;83;89;84;83 06:34:32 INFO - PROCESS | 28396 | |11;stanford-crypto-ccm;134;131;137;131;129;134;131;129;169;139 06:34:32 INFO - PROCESS | 28396 | |12;stanford-crypto-pbkdf2;176;177;176;173;172;174;172;168;167;168 06:34:32 INFO - PROCESS | 28396 | |13;stanford-crypto-sha256-iterative;64;64;64;65;63;63;65;69;63;63 06:34:32 INFO - PROCESS | 28396 | __end_tp_report 06:34:32 INFO - PROCESS | 28396 | __start_cc_report 06:34:32 INFO - PROCESS | 28396 | _x_x_mozilla_cycle_collect,1739 06:34:32 INFO - PROCESS | 28396 | __end_cc_report 06:34:32 INFO - PROCESS | 28396 | __startTimestamp1487687672855__endTimestamp 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | ------- Summary: start ------- 06:34:32 INFO - PROCESS | 28396 | Number of tests: 14 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:2.72 (2.4%) stddev-sans-first:0.83 06:34:32 INFO - PROCESS | 28396 | Values: 105.0 113.0 113.0 114.0 115.0 113.0 113.0 113.0 112.0 113.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:122.00 stddev:17.65 (14.5%) stddev-sans-first:16.81 06:34:32 INFO - PROCESS | 28396 | Values: 152.0 123.0 121.0 167.0 119.0 119.0 119.0 118.0 143.0 118.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 06:34:32 INFO - PROCESS | 28396 | Values: 161.0 161.0 162.0 160.0 163.0 161.0 161.0 163.0 161.0 162.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#3] audio-fft Cycles:10 Average:80.60 Median:76.50 stddev:9.48 (12.4%) stddev-sans-first:9.83 06:34:32 INFO - PROCESS | 28396 | Values: 75.0 78.0 76.0 104.0 78.0 76.0 76.0 77.0 91.0 75.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.00 stddev:12.36 (14.7%) stddev-sans-first:12.76 06:34:32 INFO - PROCESS | 28396 | Values: 84.0 83.0 110.0 84.0 84.0 84.0 104.0 83.0 90.0 114.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 06:34:32 INFO - PROCESS | 28396 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 06:34:32 INFO - PROCESS | 28396 | Values: 147.0 148.0 146.0 147.0 148.0 148.0 149.0 148.0 147.0 147.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:34:32 INFO - PROCESS | 28396 | Values: 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.83 (3.2%) stddev-sans-first:1.90 06:34:32 INFO - PROCESS | 28396 | Values: 56.0 56.0 56.0 56.0 62.0 57.0 56.0 57.0 57.0 57.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 06:34:32 INFO - PROCESS | 28396 | Values: 64.0 64.0 64.0 64.0 65.0 64.0 65.0 64.0 65.0 66.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:84.00 stddev:3.06 (3.6%) stddev-sans-first:3.21 06:34:32 INFO - PROCESS | 28396 | Values: 84.0 87.0 92.0 83.0 84.0 84.0 83.0 89.0 84.0 83.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:134.00 stddev:11.92 (8.9%) stddev-sans-first:12.61 06:34:32 INFO - PROCESS | 28396 | Values: 134.0 131.0 137.0 131.0 129.0 134.0 131.0 129.0 169.0 139.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.50 stddev:3.62 (2.1%) stddev-sans-first:3.59 06:34:32 INFO - PROCESS | 28396 | Values: 176.0 177.0 176.0 173.0 172.0 174.0 172.0 168.0 167.0 168.0 06:34:32 INFO - PROCESS | 28396 | 06:34:32 INFO - PROCESS | 28396 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:1.83 (2.9%) stddev-sans-first:1.94 06:34:32 INFO - PROCESS | 28396 | Values: 64.0 64.0 64.0 65.0 63.0 63.0 65.0 69.0 63.0 63.0 06:34:32 INFO - PROCESS | 28396 | -------- Summary: end -------- 06:34:32 INFO - PROCESS | 28396 | 06:34:33 INFO - TEST-INFO | 28396: exit 0 06:34:33 INFO - TEST-OK | kraken | took 60820ms 06:34:33 INFO - SUITE-END | took 1178s 06:34:34 INFO - Completed test suite (00:19:38) 06:34:34 INFO - javascript benchmark 06:34:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7070.487758800441, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.5472306515558, 3146.5280207968804, 3147.6222853257605, 3173.952390714141, 3200.503921892106, 5124.333836601246, 5184.740762961856, 5237.764300606473, 5321.308229930109, 5355.169948657955, 5206.323177986866, 5228.503292187236, 5247.422783493822, 5277.366715994077, 5287.154055351145, 4761.309610106541, 4769.5707386335225, 4857.077155340492, 4920.532549407801, 4949.975250123744, 4918.458969513351, 4949.0, 4964.652474326798, 4966.106100901836, 4973.950260497385, 4650.534946505356, 4709.317149013408, 4716.198246298103, 4761.309610106515, 4847.51524847516, 4762.1428142934255, 4763.69036012658, 4772.355731976173, 4776.283557466373, 4797.208460603996, 4790.233562629963, 4816.868036011534, 4826.251930950708, 4864.975675121602, 4868.464468908453, 5229.450907654717, 5243.711595862226, 5274.868128296785, 5310.8032577451, 5325.0, 5492.066348720727, 5650.802221922254, 5654.321481422215, 5654.575906807006, 5657.943420565783, 5560.332760068777, 5570.554355651579, 5633.718314084279, 5640.830775076754, 5642.463965923231, 5273.709945952971, 5277.604179686539, 5277.920831187535, 5278.1818818082875, 5280.841574752763, 5687.431256874363, 5722.113072473773, 5768.798092066758, 5838.182654428383, 5861.6189947653265, 5348.652337598121, 5368.91946620801, 5382.165764306539, 5615.157726341, 5626.915596266059, 5519.475649813261, 5531.640443371168, 5533.861653458615, 5536.197251398525, 5538.0585300499015, 5499.890002199933, 5511.22842802, 5511.834644960658, 5514.862128446741, 5517.69652669099, 5598.300212473441, 5602.1036634138345, 5602.85992850182, 5649.519790817826, 5650.208970744099, 4271.743695378287, 4361.345798130305, 4377.007288383366, 4387.736735795863, 4423.911521769611, 3868.3617203161725, 3885.747426417274, 3892.9610703893163, 3921.490206273166, 3959.40608908666, 5335.839924802262, 5352.116900711339, 5366.302380690485, 5381.67709937405, 5407.891842163135, 5659.434056594308, 5692.174634677948, 5694.202811606378, 5697.829065128053, 5704.828855134269, 5148.4851514848215, 5176.948230517722, 5249.291345668362, 5368.0, 5384.596155288369, 4860.9513904861205, 4911.189653707098, 4918.5573298403315, 4919.163742163841, 4920.803167873245, 5902.2622172228475, 5906.2026626406605, 5906.822795316148, 5913.704314784243, 5923.318818335861, 5681.857953551194, 5687.488126068673, 5692.231548740867, 5694.515966142924, 5700.800471983461, 5687.090065589486, 5694.487496125203, 5734.827955161185, 5813.94186058134, 5827.0, 5456.781728730806, 5499.202615620793, 5499.780008799603, 5500.477454641803, 5502.972485137709, 5601.0, 5605.32736071674, 5611.859703507445, 5613.803516876889, 5613.831585052455, 5587.273654424899, 5634.887302254014, 5665.12190610456, 5665.405132461197, 5668.178114173505, 5601.887962240814, 5611.831645050655, 5618.803341883015, 5646.971765141148, 5657.321121465286, 5319.069162896555, 5353.652012619167, 5604.411536788742, 5604.85987850307, 5606.215129881902, 5427.972860135674, 5434.701091440009, 5444.754986025718, 5445.128779395278, 5446.264754258124, 5334.413214546475, 5338.8932221356135, 5348.197770334481, 5354.678719276856, 5355.625106242525], "value": 5206.318486838669, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3938213331335, 594.0406243916077, 598.2491972574419, 598.2970010237971, 600.4655856287904, 40989.79505102474, 43166.568334316646, 43550.0, 48236.276455853105, 48321.27518087231, 42752.57247427525, 43180.0, 43215.78392108043, 43285.7835710821, 43310.0, 7403.851922961538, 7592.696292148307, 9120.407173533717, 9244.583993720298, 9315.254779617615, 10323.84514232287, 10413.114885234763, 10520.842187367196, 10677.305975111633, 11223.270487418291, 10973.23187376884, 11026.944865275662, 11029.503672334724, 11030.518133419997, 11039.116870650369, 10334.689959301233, 10388.53251603676, 10400.271980961335, 10417.114545263661, 10427.322224055411, 10389.42858142802, 10461.9476902615, 10462.738431539272, 10464.162866970624, 10475.371477711362, 42144.57855421437, 42336.0, 42494.15011699749, 42531.36202956958, 42537.0, 3860.613938606117, 3890.7471014383973, 3925.3523168677134, 3925.4896863407575, 4009.4787677601726, 31134.0, 31192.376152476823, 31241.843790780902, 31294.0, 31816.045518634477, 4159.480064991876, 4265.466816647919, 4274.0, 4407.977960110211, 4433.556644335573, 310852.0, 312004.0, 312256.0, 312663.0, 312985.0, 47414.0, 48809.9463588511, 48831.755841221275, 59916.70041649851, 60117.78586569552, 57970.0, 59545.404545953985, 59598.40401596015, 59607.70196148992, 59621.0, 15561.0, 15771.526854194393, 15775.211239437982, 15787.0, 15790.210489475481, 11801.29192248468, 11869.88130118705, 11906.821397679041, 12056.095792815398, 12082.093842961813, 16383.83616163847, 16399.262033208535, 16408.343666253455, 16459.423920162975, 16479.363301871883, 6426.4537514311805, 6435.871282574322, 6515.804525864231, 6563.573367731177, 6621.9078447828115, 862.6333808131519, 865.4677373415304, 888.3809902367551, 909.4997751236793, 916.3620426905176, 27333.0, 28752.71247287542, 29092.709072909, 29971.550426743615, 30075.548866767014, 963.5712108111823, 1070.4915165296422, 1082.9891701083045, 1094.075506197262, 1118.7315044389288, 15075.066088084237, 15277.694446111016, 15336.156511391757, 15687.0, 15816.28826702801, 337386.0, 339307.0, 339790.0, 339899.0, 341597.0, 54820.0, 54935.17597236045, 55187.724061379435, 55191.17213241805, 55230.44769552333, 1326.6617012661902, 1349.84476785169, 1354.4717560151548, 1360.2314692198877, 1386.0782579584666, 26844.463110738066, 27506.312342191606, 27523.86238068877, 28145.43709125847, 28467.430651386443, 46017.0, 46579.767101165635, 46687.53312466832, 46735.76632116818, 46745.766271169785, 274562.0, 275965.0, 277592.0, 277948.0, 278388.0, 31915.840420797747, 34456.82771586126, 35957.820210898775, 38764.80617596894, 38770.0, 39017.414738778374, 39364.409533856444, 40877.182456350114, 44517.4207459146, 45388.0, 34377.65622343744, 35877.64122358847, 35907.64092359043, 36324.45513317355, 36405.0, 34599.65400345964, 35546.822265888506, 36052.27895442129, 36190.63809361978, 36344.636553634125, 35822.641773581934, 36617.633823661425, 36633.63366366302, 36823.0, 36931.0, 30525.694743052285, 32416.675833241366, 32435.0, 32577.511337330434, 32607.673923260463, 24471.87764061168, 25014.49971000606, 33154.834225828716, 35672.64327356797, 35988.82005589955], "value": 19170.42094599093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.69058134654944, 401.39589917174646, 408.4730697400354, 408.48734837778585, 421.3700517726, 12660.556880509184, 18649.25402983881, 19142.71285930709, 20397.184112635478, 20411.26504247139, 20533.589328213428, 20845.27041553546, 20971.685424718642, 21530.784692153073, 21752.0, 5891.322497912752, 6059.303180134297, 6099.847503812418, 6108.938910610904, 6117.724702388403, 5043.697378157322, 5927.110933360009, 6018.097285407202, 6021.096835474692, 6690.83272918176, 6836.521443498957, 6836.931630683679, 6842.897356539655, 6844.9657751711175, 6849.280825513324, 5864.179014937912, 6092.177556029924, 6137.355577664371, 6253.843653908643, 6833.350831670983, 5325.121354976424, 6083.878322433527, 6088.66512341821, 6093.238345206849, 6098.786542470993, 14173.574792756233, 14753.926230368887, 15124.243787810676, 15135.848641513554, 15167.469138580096, 2593.727658595858, 2620.174644986823, 2637.709851916288, 2640.2475294540964, 2681.503921774478, 10592.775163691556, 10669.946650266776, 10899.237053406265, 11021.944890275498, 12942.417591208417, 1815.3464752689022, 1843.4746097362322, 1862.134107639954, 1873.840723538501, 1875.859310551714, 88611.82210846298, 90613.54693226557, 90622.54688726514, 91237.54381228118, 91475.54262228713, 11692.479977602858, 11924.642260732191, 11958.64124076279, 12065.638030859089, 12335.074869384833, 13905.79141312881, 14478.855211447752, 17177.0, 17230.310787568607, 17264.0, 7428.888566671504, 7764.301212890867, 7946.0, 8034.276915077554, 8114.5942702865805, 7434.1079070510805, 7524.811879703051, 7703.730369437043, 7705.267999540035, 7731.188225236382, 5039.294498770175, 5260.658057226268, 5273.762680679379, 5331.3868905076415, 5416.241726158341, 1974.8222659960672, 2026.4528577284345, 2038.541328201143, 2045.719023289022, 2048.795120487939, 866.9046404895456, 883.0948278014957, 892.7991201979503, 895.0735988252128, 913.917747402737, 19179.80820191808, 19218.0, 19941.700874486894, 20103.095360708805, 20180.39458816238, 865.1175800683268, 868.4268382867277, 890.8218356328761, 891.6567121658079, 896.7444278380631, 10607.84088238677, 10793.78412431747, 10894.455277236106, 11053.0, 11085.168612354106, 561332.0, 570831.0, 583261.0, 608006.0, 609309.0, 94355.0, 95806.0, 97574.51212743891, 97756.0, 99160.0, 976.2092704909046, 1011.049613363436, 1030.469308306223, 1033.8811036730722, 1034.9689509314733, 16151.85109592248, 16599.668006639557, 16875.240614172148, 17168.914155429142, 17284.222210000833, 44669.0, 44693.32960005668, 44755.32867007062, 44858.77570612126, 46160.53839461697, 59791.40208597858, 60553.69723151356, 60946.0, 61338.0, 62732.37267627265, 6847.965760171366, 8447.113053129333, 8522.70170544028, 8584.871226931476, 8964.3276754245, 13180.340982951006, 13222.603321900358, 13328.533501327407, 13551.457941682222, 13756.243406612732, 11177.329360238411, 11379.772404551697, 11386.54453821871, 11660.825087623523, 11722.765544688888, 11158.44207789591, 11199.328040317607, 11449.198556100993, 11790.23363481371, 11955.222910510789, 10980.835287470534, 11295.943520282346, 11963.880361196276, 11971.0, 12145.392730363623, 8631.91368086311, 8710.956445217987, 8741.431806932529, 8769.166929141722, 8973.416727912665, 10618.628348007782, 11489.482973266391, 11530.654080377602, 11648.242864213798, 11916.463759131033], "value": 9382.558546757991, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.9573712788617, 1425.4796999095333, 1428.9356978935941, 1433.3549902543857, 1438.6331485471208, 8179.836403271938, 8621.05168431472, 8625.482471051733, 8631.48211107334, 8634.395592308541, 8337.95831020844, 8536.402451828359, 8553.52955587444, 8567.271781898531, 8737.781555461117, 2214.977850221493, 2249.156566287642, 2255.7744225577394, 2257.3340864445026, 2257.8758168300737, 1868.9439316820517, 2206.8013878750908, 2238.1942500699734, 2245.38251980705, 2246.8428759189032, 1938.4087853204767, 2152.7309086364203, 2209.569134018867, 2216.1467834883524, 2233.519793244452, 2172.2180015194517, 2200.5378870437225, 2206.5035367042447, 2208.138825857917, 2208.977910220893, 2169.110664627511, 2213.9778602213933, 2218.9334319970426, 2220.3449982255274, 2221.144859229192, 8668.826623467496, 8731.650733970633, 8732.257758090569, 8737.737867863974, 8740.825183496358, 4901.5588597026435, 5018.172001619729, 5023.34696489454, 5028.522290382408, 5032.295478632995, 2869.153599688097, 3102.0848849589424, 3163.9367212655616, 3175.952360714568, 3179.1257404213793, 1255.1778585026823, 1304.3804193008245, 1306.8497122830806, 1354.1671871798872, 1361.6255529729306, 11747.060235181165, 11846.407679615984, 11857.703557411134, 11896.88103118975, 11902.702432439171, 4014.1168942833115, 4277.657787377002, 4319.503257125408, 4320.978395108067, 4354.216241076637, 9401.905980940102, 9423.670171543963, 9426.481543515172, 9442.055794420641, 9471.337006409485, 5047.141985862412, 5058.898822023612, 5059.544640982329, 5081.8729531762, 5089.720065396439, 4521.412216411845, 4555.2483840166005, 4649.883752906205, 4680.882977925579, 4688.5545873142, 3497.405441075023, 3525.806080665588, 3564.322778672044, 3579.2125732338845, 3581.910452238663, 1082.4587706146926, 1084.4252546150553, 1092.6721983404946, 1096.2271598523023, 1098.7857367813199, 726.6294189963157, 746.1493896957472, 773.377431167904, 780.3055280800093, 855.8074433252593, 5363.517283444508, 5469.808556700496, 5582.2694964561115, 5597.656562424989, 5691.121929763168, 979.9461029643295, 1013.9188864890791, 1022.243539780572, 1024.9077583017565, 1027.609508386823, 3529.7176225901867, 3725.2177042820767, 3873.1479074603544, 3906.140649057203, 4024.5388965489656, 10204.38773673582, 10436.686899393031, 10508.947455262674, 10512.894871051345, 10519.368837869752, 9808.656697015713, 10235.130013948748, 10465.372077675363, 10466.162706983423, 10473.266871319087, 7917.2874441300555, 8138.959305203436, 8139.674413023531, 8158.673653053811, 8163.755087347389, 5303.708296043755, 5404.216388623706, 5564.137558678412, 5588.077967135378, 5801.1878337032, 9078.54607269647, 9380.859287110563, 9393.24854011691, 9543.33196676226, 9659.9034009659, 9258.35191536586, 9528.904710952802, 9624.37441566296, 9646.0, 9797.020297970146, 7274.30894065063, 7324.0, 7333.156686980852, 7396.741114060982, 7403.0, 8115.715949941724, 8194.426390152632, 8196.180381961756, 8229.300509456763, 8254.0507841599, 7104.786856394317, 7159.212486626571, 7283.817904552428, 7361.484696071224, 7523.586202758901, 6937.583744975318, 7203.387712044535, 7340.853182936418, 7360.558366498028, 7479.626018698935, 6568.934310656832, 6777.220619628806, 6777.322267773183, 6777.8983315249325, 6782.864342713019, 4992.500749924979, 5062.063518249136, 5082.567981721447, 5148.716820574904, 5281.10221262394, 4792.257200133985, 4832.081904438145, 4885.340479035427, 5158.200478925773, 5231.372235331784], "value": 4411.635719608811, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.34048945512825, 396.2095619239616, 414.97095203335766, 416.9353750168723, 421.03792833375735, 5219.817306394277, 5225.686458812469, 5258.789648414068, 5273.18265668821, 5321.707306098173, 5064.924026139611, 5192.480751924805, 5217.165253559431, 5243.4232234454175, 5256.474352564742, 4200.180964711883, 4201.39079833424, 4203.495580530325, 4214.810333534998, 4215.346621273692, 4209.621134097931, 4210.179015092058, 4214.97892510537, 4222.282212023963, 4234.555371685975, 3993.960060399402, 4078.143589846136, 4132.834686612532, 4189.3087640539425, 4192.7484350938885, 4085.7344272622186, 4098.73358231717, 4113.794310284474, 4128.422020917089, 4134.4831896013, 3622.873199438033, 3626.582942961567, 3634.8727794527063, 3635.4728564358284, 3663.816809159531, 4870.683405578661, 5146.974265128688, 5147.253648221024, 5152.175651895679, 5159.9422118466, 4717.198076327033, 4739.431268247798, 4743.810247590092, 4753.47711751707, 4772.475027746945, 5528.170774383834, 5542.39033706292, 5545.3622833374275, 5545.445455454463, 5546.0, 3818.5417749869916, 4028.979855100735, 4035.3341698619693, 4046.3121269384274, 4049.7570145791346, 5482.945170548283, 5580.553555715533, 5653.530082178622, 5655.37790843011, 5691.544676425876, 4048.1903619276263, 4056.4320995060716, 4058.1680755445063, 4122.690798190148, 4126.896827579335, 4182.832686692498, 4183.267928112568, 4186.958130418657, 4189.371594260885, 4191.8532851350055, 4441.800118994653, 4464.620507256855, 4572.4055872737, 4677.929831052537, 4698.506656800987, 5427.755750991215, 5429.239906413105, 5468.125099983983, 5477.260569823103, 5485.698286594195, 1358.7282543491342, 1381.9861801381858, 1391.8816900563565, 1393.6794537256487, 1396.2250950722446, 937.381328323317, 943.2642538819731, 957.8132264208413, 980.0150848397315, 997.1424574865609, 5126.2054381570915, 5142.8971420572125, 5154.639175257696, 5167.302414174113, 5175.870603234949, 5484.945150548523, 5484.972575137099, 5485.286912701324, 5486.91769623456, 5490.533304669148, 4956.975215123901, 5158.303629010035, 5165.405978312467, 5273.6572122811895, 5314.0, 2870.2250392394353, 2887.1627228104035, 2905.2155917902046, 2918.1683220282116, 2922.240217543454, 5689.715514224355, 5701.4298570142655, 5711.657300561979, 5714.0, 5722.170285308607, 5456.427075157131, 5754.021816291241, 5760.884782304414, 5763.394843541451, 5790.71046447676, 5890.823275301748, 5894.823155305347, 5903.350631430453, 5909.690573278977, 5913.05391137416, 5531.612787104864, 5651.773929042793, 5676.545876329967, 5685.759117303098, 5698.629589076697, 5570.2201691762375, 5574.749136288796, 5581.162825576196, 5642.586235327732, 5655.688937108499, 5634.6337488063145, 5752.654840709571, 5768.53851691872, 5770.662881532986, 5865.501432378295, 3839.6928245739714, 4089.8568550100604, 4097.405876148002, 4145.419641250288, 4157.625813676663, 5623.578231632726, 5651.547876169814, 5673.914891276717, 5685.374608793112, 5692.743826527733, 5278.627556835174, 5479.726013699379, 5511.311086114236, 5529.0, 5549.0, 5544.417836127149, 5566.91649625264, 5572.442755724395, 5580.298009107261, 5586.441355864544, 5311.150215965427, 5540.279763630702, 5548.140038294071, 5552.639078459887, 5562.805301814417, 5557.8610534735335, 5561.304836895388, 5567.66594004361, 5599.468050535193, 5600.467955544217, 5416.458354164552, 5425.348958125051, 5425.376081750649, 5433.56531477473, 5444.455554444524], "value": 4233.358872429418, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.9424043196761, 793.9166387529309, 837.6440012994477, 843.1147295339891, 845.8688903220003, 15198.772018419719, 16392.426265080725, 16394.26225819838, 16416.671666566675, 16526.0, 15344.846551534481, 15530.30113644886, 15535.223238838069, 15559.688806223898, 15562.144082075481, 771.1055175995846, 785.0696924144878, 788.6293442082241, 790.7113903425237, 805.1586092533288, 794.2335646101511, 797.696875187428, 801.0867610923552, 802.7471346525825, 803.0644299391203, 833.4249367936135, 833.6082041440544, 835.4319063037133, 835.8871552340418, 835.9456635318716, 826.3389288569181, 826.4917075998278, 827.5986146718836, 828.6354004238146, 829.8755186721979, 605.3613437823095, 609.6342194683199, 611.6299638718556, 612.4549151255386, 614.1248720573166, 15973.0, 15983.840161598468, 15988.200589970571, 16086.0, 16695.83304166967, 14022.368993395321, 14346.928265358607, 14358.69830684193, 14368.928155359261, 14561.126332419983, 12243.32661703606, 12279.263244205287, 12331.0, 12554.435050422662, 12584.0, 3637.672249628893, 3691.741578089535, 3721.106934335767, 3724.683401910841, 3726.8136593170234, 66957.0, 71661.0, 72340.63829680817, 72976.6351168246, 76337.0, 10125.291229613857, 10226.335288206243, 10325.122364599094, 10779.784404312026, 11062.336259824437, 17947.551311217325, 18054.729179062324, 18059.187336569885, 18066.458006259832, 18130.818691813176, 7238.384737297283, 7248.316784208756, 7337.266273372727, 7479.0, 7625.504342217738, 4226.72892466018, 4314.072474418015, 4343.066240758252, 4401.440906395939, 4415.27148020573, 3303.7687361884437, 3387.2040070583394, 3393.9151521211675, 3396.677315655009, 3401.523786669868, 581.1428143488405, 604.5133667397699, 632.5192853431425, 637.6174295422802, 642.210081599635, 692.736760031195, 712.1489819665553, 712.803978905805, 732.3010615372391, 737.9483436159525, 9179.77050573741, 9467.579863020603, 9540.379875308083, 10261.127804136582, 10504.789904201873, 717.3185473799911, 748.4648476339444, 753.9811504712426, 763.522798251093, 779.4193325972074, 4215.346621273708, 4550.567696068868, 4578.908421831545, 4580.770961451981, 4619.376384188092, 72620.63689681518, 73948.63025684837, 75844.24155758372, 75999.0, 77095.0, 66922.33077669259, 68159.0, 68627.0, 68674.3132568678, 69177.30822691809, 60768.696156518934, 61085.11319094551, 61449.38550614437, 61980.0, 62092.0, 10122.79754404893, 10518.316309439862, 10591.89408105909, 10784.23043671632, 10838.403887786248, 37302.44046339253, 38303.0, 38495.422568662056, 39168.0, 43362.56637433711, 50011.74947127315, 53326.73336633292, 57595.424045759006, 57867.0, 59188.0, 13723.382447790073, 13759.862401375858, 13801.0, 14243.928780356031, 14436.711265774837, 15278.923605382346, 15419.922900385427, 15567.532974010797, 15767.053976761432, 15965.760513592537, 13769.0, 13935.163890166623, 14142.92928535392, 14358.928205358907, 14864.85135148678, 12807.487700491876, 13389.732205356033, 13390.93304533471, 13839.584812455643, 14031.579052628438, 12873.613791586267, 13342.666433339244, 13421.597352079454, 13528.391222395212, 13596.388162532907, 9371.437713737198, 9560.76098097553, 9604.0, 9760.658376956773, 9933.106020458194, 10202.540885660015, 10445.68662940113, 11017.504212310625, 11311.717207069889, 11692.532298707956], "value": 7143.0257765055985, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 113.0, 114.0, 115.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 121.0, 167.0, 119.0, 119.0, 119.0, 118.0, 143.0, 118.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 163.0, 161.0, 161.0, 163.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 104.0, 78.0, 76.0, 76.0, 77.0, 91.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 110.0, 84.0, 84.0, 84.0, 104.0, 83.0, 90.0, 114.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 148.0, 148.0, 149.0, 148.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 62.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 66.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 92.0, 83.0, 84.0, 84.0, 83.0, 89.0, 84.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 131.0, 137.0, 131.0, 129.0, 134.0, 131.0, 129.0, 169.0, 139.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 176.0, 173.0, 172.0, 174.0, 172.0, 168.0, 167.0, 168.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 63.0, 63.0, 65.0, 69.0, 63.0, 63.0], "value": 64.3, "unit": "score"}]}]} 06:34:34 INFO - Return code: 0 06:34:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:34:34 ERROR - # TBPL SUCCESS # 06:34:34 INFO - Running post-action listener: _resource_record_post_action 06:34:34 INFO - [mozharness: 2017-02-21 14:34:34.172446Z] Finished run-tests step (success) 06:34:34 INFO - Running post-run listener: _resource_record_post_run 06:34:34 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 365047808; Read time: 8; Write time: 241512 06:34:34 INFO - TinderboxPrint: CPU usage
13.1% 06:34:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 06:34:34 INFO - TinderboxPrint: I/O write bytes / time
365,047,808 / 241,512 06:34:34 INFO - TinderboxPrint: CPU idle
8,290.0 (86.9%) 06:34:34 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 06:34:34 INFO - TinderboxPrint: Swap in / out
0 / 0 06:34:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112336896; Read time: 0; Write time: 77584 06:34:34 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251740160; Read time: 8; Write time: 163904 06:34:34 INFO - Running post-run listener: _upload_blobber_files 06:34:34 INFO - Blob upload gear active. 06:34:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:34:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:34:34 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'] 06:34:34 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 06:34:35 INFO - (blobuploader) - INFO - Open directory for files ... 06:34:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:34:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:34:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:34:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:34:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:34:36 INFO - (blobuploader) - INFO - Done attempting. 06:34:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:34:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:34:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:34:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:34:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:34:36 INFO - (blobuploader) - INFO - Done attempting. 06:34:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:34:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:34:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:34:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:34:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:34:40 INFO - (blobuploader) - INFO - Done attempting. 06:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:34:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:34:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:34:41 INFO - (blobuploader) - INFO - Done attempting. 06:34:41 INFO - (blobuploader) - INFO - Iteration through files over. 06:34:41 INFO - Return code: 0 06:34:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:34:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:34:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0296434cfd1c3cfa3e7dbad0bb2ca36354ae216b77426e3fe5f9ca3bd18a605b6972f38fbf0e8d340bf0bee8252934ddca58f2179c761457a7617f8f45a91f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c7a301e575734a1e8db8a53bf100bc38343ce355f2f8ac4f38e119385b2b826adbf9520cb7b8988943c9de1ca8e776ecdfaf460b76ec61d1abe04eb66b651ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19811fb6dd799ba40095edf0fb03afe35f1e16292f1b04b5a07ef748fa34ce02530bc045f54e510b82b9c846ed31b9c4ff8b37e18fd3e3a65377f127745bf86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/539424b8edc181eff859d32022cf7d9b83984e7ba3fc18121cccda9063951623bb17be7dbc9aebfdfe4c2073c5f84287e1e7be1c2fb30ac6b00d482f13a63264"} 06:34:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:34:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:34:41 INFO - Contents: 06:34:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0296434cfd1c3cfa3e7dbad0bb2ca36354ae216b77426e3fe5f9ca3bd18a605b6972f38fbf0e8d340bf0bee8252934ddca58f2179c761457a7617f8f45a91f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c7a301e575734a1e8db8a53bf100bc38343ce355f2f8ac4f38e119385b2b826adbf9520cb7b8988943c9de1ca8e776ecdfaf460b76ec61d1abe04eb66b651ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19811fb6dd799ba40095edf0fb03afe35f1e16292f1b04b5a07ef748fa34ce02530bc045f54e510b82b9c846ed31b9c4ff8b37e18fd3e3a65377f127745bf86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/539424b8edc181eff859d32022cf7d9b83984e7ba3fc18121cccda9063951623bb17be7dbc9aebfdfe4c2073c5f84287e1e7be1c2fb30ac6b00d482f13a63264"} 06:34:41 INFO - Running post-run listener: copy_logs_to_upload_dir 06:34:41 INFO - Copying logs to upload dir... 06:34:41 INFO - mkdir: /builds/slave/test/build/upload/logs 06:34:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.978124 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-21 06:34:41.207117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 06:34:41.210211) ========= 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=62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0296434cfd1c3cfa3e7dbad0bb2ca36354ae216b77426e3fe5f9ca3bd18a605b6972f38fbf0e8d340bf0bee8252934ddca58f2179c761457a7617f8f45a91f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c7a301e575734a1e8db8a53bf100bc38343ce355f2f8ac4f38e119385b2b826adbf9520cb7b8988943c9de1ca8e776ecdfaf460b76ec61d1abe04eb66b651ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19811fb6dd799ba40095edf0fb03afe35f1e16292f1b04b5a07ef748fa34ce02530bc045f54e510b82b9c846ed31b9c4ff8b37e18fd3e3a65377f127745bf86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/539424b8edc181eff859d32022cf7d9b83984e7ba3fc18121cccda9063951623bb17be7dbc9aebfdfe4c2073c5f84287e1e7be1c2fb30ac6b00d482f13a63264"} build_url:https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/E6zG7twuRVmcmeL9CaOByA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0296434cfd1c3cfa3e7dbad0bb2ca36354ae216b77426e3fe5f9ca3bd18a605b6972f38fbf0e8d340bf0bee8252934ddca58f2179c761457a7617f8f45a91f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c7a301e575734a1e8db8a53bf100bc38343ce355f2f8ac4f38e119385b2b826adbf9520cb7b8988943c9de1ca8e776ecdfaf460b76ec61d1abe04eb66b651ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19811fb6dd799ba40095edf0fb03afe35f1e16292f1b04b5a07ef748fa34ce02530bc045f54e510b82b9c846ed31b9c4ff8b37e18fd3e3a65377f127745bf86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/539424b8edc181eff859d32022cf7d9b83984e7ba3fc18121cccda9063951623bb17be7dbc9aebfdfe4c2073c5f84287e1e7be1c2fb30ac6b00d482f13a63264"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 06:34:41.235727) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:34:41.236138) ========= 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=62c568580acbfdaac3eec7be000001dc-1487686422.636617-2113525606 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:34:41.318678) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 06:34:41.319029) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 06:34:41.319441) ========= ========= Total master_lag: 0.17 =========