builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1487250957.28 results: success (0) revision: 8c8b54b13be7ec12cb8e104b772162a80b524497 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.282904) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.283505) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.283785) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.312035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.312289) ========= 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=cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 05:15:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-02-16 05:15:58 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107539 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.434145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.434581) ========= 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=cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017457 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.484420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:57.484955) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c8b54b13be7ec12cb8e104b772162a80b524497 --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 8c8b54b13be7ec12cb8e104b772162a80b524497 --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=cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 05:15:58,153 truncating revision to first 12 chars 2017-02-16 05:15:58,153 Setting DEBUG logging. 2017-02-16 05:15:58,153 attempt 1/10 2017-02-16 05:15:58,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c8b54b13be7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 05:15:58,428 unpacking tar archive at: mozilla-central-8c8b54b13be7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558913 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:58.057761) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:58.058069) ========= script_repo_revision: 8c8b54b13be7ec12cb8e104b772162a80b524497 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:58.058459) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:58.058732) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 05:15:58.068434) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-16 05:15:58.068846) ========= /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=cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:15:58 INFO - MultiFileLogger online at 20170216 05:15:58 in /builds/slave/test 05:15:58 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 05:15:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:58 INFO - {'append_to_log': False, 05:15:58 INFO - 'base_work_dir': '/builds/slave/test', 05:15:58 INFO - 'blob_upload_branch': 'Firefox', 05:15:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:58 INFO - 'branch': 'Firefox', 05:15:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:58 INFO - 'config_files': ('talos/linux_config.py',), 05:15:58 INFO - 'default_actions': ('clobber', 05:15:58 INFO - 'read-buildbot-config', 05:15:58 INFO - 'download-and-extract', 05:15:58 INFO - 'populate-webroot', 05:15:58 INFO - 'create-virtualenv', 05:15:58 INFO - 'install', 05:15:58 INFO - 'run-tests'), 05:15:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:58 INFO - 'download_minidump_stackwalk': True, 05:15:58 INFO - 'download_symbols': 'ondemand', 05:15:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:58 INFO - 'installer_path': 'installer.exe', 05:15:58 INFO - 'log_level': 'info', 05:15:58 INFO - 'log_name': 'talos', 05:15:58 INFO - 'log_to_console': True, 05:15:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:15:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:15:58 INFO - 'opt_config_files': (), 05:15:58 INFO - 'pip_index': False, 05:15:58 INFO - 'sps_profile': False, 05:15:58 INFO - 'sps_profile_interval': 0, 05:15:58 INFO - 'suite': 'dromaeojs', 05:15:58 INFO - 'system_bits': '32', 05:15:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:58 INFO - 'title': 'talos-linux64-ix-054', 05:15:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:58 INFO - 'use_talos_json': True, 05:15:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:58 INFO - 'work_dir': 'build'} 05:15:58 INFO - [mozharness: 2017-02-16 13:15:58.839655Z] Running clobber step. 05:15:58 INFO - Running pre-action listener: _resource_record_pre_action 05:15:58 INFO - Running main action method: clobber 05:15:58 INFO - rmtree: /builds/slave/test/build 05:15:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:59 INFO - Running post-action listener: _resource_record_post_action 05:15:59 INFO - [mozharness: 2017-02-16 13:15:59.768419Z] Finished clobber step (success) 05:15:59 INFO - [mozharness: 2017-02-16 13:15:59.768520Z] Running read-buildbot-config step. 05:15:59 INFO - Running pre-action listener: _resource_record_pre_action 05:15:59 INFO - Running main action method: read_buildbot_config 05:15:59 INFO - Using buildbot properties: 05:15:59 INFO - { 05:15:59 INFO - "project": "", 05:15:59 INFO - "product": "firefox", 05:15:59 INFO - "who": "cbook@mozilla.com", 05:15:59 INFO - "installer_path": "public/build/target.tar.bz2", 05:15:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:15:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:15:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:15:59 INFO - "stage_platform": "linux64-pgo", 05:15:59 INFO - "basedir": "/builds/slave/test", 05:15:59 INFO - "buildnumber": 36, 05:15:59 INFO - "platform": "ubuntu64_hw", 05:15:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:15:59 INFO - "slavebuilddir": "test", 05:15:59 INFO - "taskId": "MHvInttFQSGrtt50L9oCFw", 05:15:59 INFO - "branch": "mozilla-central", 05:15:59 INFO - "script_repo_revision": "production", 05:15:59 INFO - "revision": "8c8b54b13be7ec12cb8e104b772162a80b524497", 05:15:59 INFO - "slavename": "talos-linux64-ix-054", 05:15:59 INFO - "repo_path": "mozilla-central" 05:15:59 INFO - } 05:15:59 INFO - Finding installer, test and symbols from parent task. 05:15:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MHvInttFQSGrtt50L9oCFw'}, attempt #1 05:16:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MHvInttFQSGrtt50L9oCFw'}, attempt #1 05:16:00 INFO - Task dependencies: Acj_ciNEQV6TPMsd9BDr9A 05:16:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A'}, attempt #1 05:16:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2 05:16:01 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/test_packages.json 05:16:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.crashreporter-symbols.zip 05:16:01 INFO - Running post-action listener: _resource_record_post_action 05:16:01 INFO - [mozharness: 2017-02-16 13:16:01.152112Z] Finished read-buildbot-config step (success) 05:16:01 INFO - [mozharness: 2017-02-16 13:16:01.152329Z] Running download-and-extract step. 05:16:01 INFO - Running pre-action listener: _resource_record_pre_action 05:16:01 INFO - Running main action method: download_and_extract 05:16:01 INFO - mkdir: /builds/slave/test/build/tests 05:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:01 INFO - trying https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.test_packages.json 05:16:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:16:01 INFO - Downloaded 896 bytes. 05:16:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:16:01 INFO - Using the following test package requirements: 05:16:01 INFO - {u'common': [u'target.common.tests.zip'], 05:16:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:16:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:16:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:16:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:16:01 INFO - u'mozbase': [u'target.common.tests.zip'], 05:16:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:16:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:16:01 INFO - u'web-platform': [u'target.common.tests.zip', 05:16:01 INFO - u'target.web-platform.tests.zip'], 05:16:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:16:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:16:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.common.tests.zip 05:16:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:16:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.common.tests.zip into memory 05:16:02 INFO - Expected file size: 59051138 05:16:02 INFO - Obtained file size: 59051138 05:16:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:16:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.common.tests.zip 05:16:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:16:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.common.tests.zip into memory 05:16:13 INFO - Expected file size: 59051138 05:16:13 INFO - Obtained file size: 59051138 05:16:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.talos.tests.zip 05:16:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:16:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.talos.tests.zip into memory 05:16:25 INFO - Expected file size: 14403647 05:16:25 INFO - Obtained file size: 14403647 05:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:25 INFO - trying https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2 05:16:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:16:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:16:27 INFO - Downloaded 61856247 bytes. 05:16:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2 05:16:27 INFO - mkdir: /builds/slave/test/properties 05:16:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:16:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:16:27 INFO - Contents: 05:16:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2 05:16:27 INFO - Running post-action listener: _resource_record_post_action 05:16:27 INFO - Running post-action listener: set_extra_try_arguments 05:16:27 INFO - [mozharness: 2017-02-16 13:16:27.337298Z] Finished download-and-extract step (success) 05:16:27 INFO - [mozharness: 2017-02-16 13:16:27.337441Z] Running populate-webroot step. 05:16:27 INFO - Running pre-action listener: _resource_record_pre_action 05:16:27 INFO - Running main action method: populate_webroot 05:16:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:16:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:16:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:16:27 INFO - '--tppagecycles', 05:16:27 INFO - '7'], 05:16:27 INFO - 'tests': ['tsvgm']}}, 05:16:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:16:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:16:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:16:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:16:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['damp', 'tps']}, 05:16:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'tests': ['damp', 'tps']}, 05:16:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['dromaeo_dom']}, 05:16:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:16:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['basic_compositor_video']}, 05:16:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:16:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['a11yr', 05:16:27 INFO - 'ts_paint', 05:16:27 INFO - 'tpaint', 05:16:27 INFO - 'sessionrestore', 05:16:27 INFO - 'sessionrestore_no_auto_restore', 05:16:27 INFO - 'tabpaint']}, 05:16:27 INFO - 'other-e10s': {'tests': ['a11yr', 05:16:27 INFO - 'ts_paint', 05:16:27 INFO - 'tpaint', 05:16:27 INFO - 'sessionrestore', 05:16:27 INFO - 'sessionrestore_no_auto_restore', 05:16:27 INFO - 'tabpaint']}, 05:16:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:16:27 INFO - 'ts_paint', 05:16:27 INFO - 'tpaint', 05:16:27 INFO - 'sessionrestore', 05:16:27 INFO - 'sessionrestore_no_auto_restore', 05:16:27 INFO - 'tabpaint']}, 05:16:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:16:27 INFO - 'ts_paint', 05:16:27 INFO - 'tpaint', 05:16:27 INFO - 'sessionrestore', 05:16:27 INFO - 'sessionrestore_no_auto_restore', 05:16:27 INFO - 'tabpaint']}, 05:16:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['a11yr', 05:16:27 INFO - 'ts_paint', 05:16:27 INFO - 'tpaint', 05:16:27 INFO - 'sessionrestore', 05:16:27 INFO - 'sessionrestore_no_auto_restore', 05:16:27 INFO - 'tabpaint']}, 05:16:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['a11yr', 05:16:27 INFO - 'ts_paint', 05:16:27 INFO - 'tpaint', 05:16:27 INFO - 'sessionrestore', 05:16:27 INFO - 'sessionrestore_no_auto_restore', 05:16:27 INFO - 'tabpaint']}, 05:16:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['tsvgx', 05:16:27 INFO - 'tsvgr_opacity', 05:16:27 INFO - 'tart', 05:16:27 INFO - 'tscrollx', 05:16:27 INFO - 'cart', 05:16:27 INFO - 'tsvg_static']}, 05:16:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:16:27 INFO - 'tsvgr_opacity', 05:16:27 INFO - 'tart', 05:16:27 INFO - 'tscrollx', 05:16:27 INFO - 'cart', 05:16:27 INFO - 'tsvg_static']}, 05:16:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'talos_options': ['--disable-e10s'], 05:16:27 INFO - 'tests': ['tp5o']}, 05:16:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:16:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'talos_options': ['--disable-e10s', 05:16:27 INFO - '--xperf_path', 05:16:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:27 INFO - 'tests': ['tp5n']}, 05:16:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:16:27 INFO - 'talos_options': ['--xperf_path', 05:16:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:27 INFO - 'tests': ['tp5n']}}, 05:16:27 INFO - 'talos.zip': {'path': '', 05:16:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:16:27 INFO - Running post-action listener: _resource_record_post_action 05:16:27 INFO - [mozharness: 2017-02-16 13:16:27.354624Z] Finished populate-webroot step (success) 05:16:27 INFO - [mozharness: 2017-02-16 13:16:27.354718Z] Running create-virtualenv step. 05:16:27 INFO - Running pre-action listener: _resource_record_pre_action 05:16:27 INFO - Running main action method: create_virtualenv 05:16:27 INFO - Creating virtualenv /builds/slave/test/build/venv 05:16:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:16:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:16:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:16:27 INFO - Using real prefix '/usr' 05:16:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:16:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:16:29 INFO - Installing pip.................done. 05:16:29 INFO - Return code: 0 05:16:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:16:30 INFO - Reading from file tmpfile_stdout 05:16:30 INFO - Output received: 05:16:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:16:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:16:30 INFO - Using env: {'DISPLAY': ':0', 05:16:30 INFO - 'HOME': '/home/cltbld', 05:16:30 INFO - 'LANG': 'en_US.UTF-8', 05:16:30 INFO - 'LANGUAGE': 'en_US:en', 05:16:30 INFO - 'LOGNAME': 'cltbld', 05:16:30 INFO - 'MAIL': '/var/mail/cltbld', 05:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:30 INFO - 'MOZ_NO_REMOTE': '1', 05:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:30 INFO - 'NO_EM_RESTART': '1', 05:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:30 INFO - 'PWD': '/builds/slave/test', 05:16:30 INFO - 'SHELL': '/bin/bash', 05:16:30 INFO - 'SHLVL': '1', 05:16:30 INFO - 'TERM': 'linux', 05:16:30 INFO - 'TMOUT': '86400', 05:16:30 INFO - 'USER': 'cltbld', 05:16:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:30 INFO - '_': '/tools/buildbot/bin/python'} 05:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:16:30 INFO - Cleaning up... 05:16:30 INFO - Return code: 0 05:16:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:16:30 INFO - Using env: {'DISPLAY': ':0', 05:16:30 INFO - 'HOME': '/home/cltbld', 05:16:30 INFO - 'LANG': 'en_US.UTF-8', 05:16:30 INFO - 'LANGUAGE': 'en_US:en', 05:16:30 INFO - 'LOGNAME': 'cltbld', 05:16:30 INFO - 'MAIL': '/var/mail/cltbld', 05:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:30 INFO - 'MOZ_NO_REMOTE': '1', 05:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:30 INFO - 'NO_EM_RESTART': '1', 05:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:30 INFO - 'PWD': '/builds/slave/test', 05:16:30 INFO - 'SHELL': '/bin/bash', 05:16:30 INFO - 'SHLVL': '1', 05:16:30 INFO - 'TERM': 'linux', 05:16:30 INFO - 'TMOUT': '86400', 05:16:30 INFO - 'USER': 'cltbld', 05:16:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:30 INFO - '_': '/tools/buildbot/bin/python'} 05:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:30 INFO - Downloading/unpacking psutil>=3.1.1 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:16:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:32 INFO - Installing collected packages: psutil 05:16:32 INFO - Running setup.py install for psutil 05:16:32 INFO - building 'psutil._psutil_linux' extension 05:16:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:16:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:16:33 INFO - building 'psutil._psutil_posix' extension 05:16:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:16:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:16:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:33 INFO - Successfully installed psutil 05:16:33 INFO - Cleaning up... 05:16:33 INFO - Return code: 0 05:16:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:33 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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:16:33 INFO - Using env: {'DISPLAY': ':0', 05:16:33 INFO - 'HOME': '/home/cltbld', 05:16:33 INFO - 'LANG': 'en_US.UTF-8', 05:16:33 INFO - 'LANGUAGE': 'en_US:en', 05:16:33 INFO - 'LOGNAME': 'cltbld', 05:16:33 INFO - 'MAIL': '/var/mail/cltbld', 05:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:33 INFO - 'MOZ_NO_REMOTE': '1', 05:16:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:33 INFO - 'NO_EM_RESTART': '1', 05:16:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:33 INFO - 'PWD': '/builds/slave/test', 05:16:33 INFO - 'SHELL': '/bin/bash', 05:16:33 INFO - 'SHLVL': '1', 05:16:33 INFO - 'TERM': 'linux', 05:16:33 INFO - 'TMOUT': '86400', 05:16:33 INFO - 'USER': 'cltbld', 05:16:33 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:33 INFO - '_': '/tools/buildbot/bin/python'} 05:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:16:35 INFO - Installing collected packages: mozsystemmonitor 05:16:35 INFO - Running setup.py install for mozsystemmonitor 05:16:35 INFO - Successfully installed mozsystemmonitor 05:16:35 INFO - Cleaning up... 05:16:35 INFO - Return code: 0 05:16:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:16:35 INFO - Using env: {'DISPLAY': ':0', 05:16:35 INFO - 'HOME': '/home/cltbld', 05:16:35 INFO - 'LANG': 'en_US.UTF-8', 05:16:35 INFO - 'LANGUAGE': 'en_US:en', 05:16:35 INFO - 'LOGNAME': 'cltbld', 05:16:35 INFO - 'MAIL': '/var/mail/cltbld', 05:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:35 INFO - 'MOZ_NO_REMOTE': '1', 05:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:35 INFO - 'NO_EM_RESTART': '1', 05:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:35 INFO - 'PWD': '/builds/slave/test', 05:16:35 INFO - 'SHELL': '/bin/bash', 05:16:35 INFO - 'SHLVL': '1', 05:16:35 INFO - 'TERM': 'linux', 05:16:35 INFO - 'TMOUT': '86400', 05:16:35 INFO - 'USER': 'cltbld', 05:16:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:35 INFO - '_': '/tools/buildbot/bin/python'} 05:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:35 INFO - Downloading/unpacking jsonschema==2.5.1 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:16:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:16:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:16:38 INFO - warning: no files found matching '*.txt' 05:16:38 INFO - no previously-included directories found matching 'build' 05:16:38 INFO - no previously-included directories found matching 'dist' 05:16:38 INFO - no previously-included directories found matching '.git*' 05:16:38 INFO - Installing collected packages: jsonschema, functools32 05:16:38 INFO - Running setup.py install for functools32 05:16:38 INFO - warning: no files found matching '*.txt' 05:16:38 INFO - no previously-included directories found matching 'build' 05:16:38 INFO - no previously-included directories found matching 'dist' 05:16:38 INFO - no previously-included directories found matching '.git*' 05:16:38 INFO - Successfully installed jsonschema functools32 05:16:38 INFO - Cleaning up... 05:16:38 INFO - Return code: 0 05:16:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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', '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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:16: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 functools32==3.2.3-2 05:16:38 INFO - Using env: {'DISPLAY': ':0', 05:16:38 INFO - 'HOME': '/home/cltbld', 05:16:38 INFO - 'LANG': 'en_US.UTF-8', 05:16:38 INFO - 'LANGUAGE': 'en_US:en', 05:16:38 INFO - 'LOGNAME': 'cltbld', 05:16:38 INFO - 'MAIL': '/var/mail/cltbld', 05:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:38 INFO - 'MOZ_NO_REMOTE': '1', 05:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:38 INFO - 'NO_EM_RESTART': '1', 05:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:38 INFO - 'PWD': '/builds/slave/test', 05:16:38 INFO - 'SHELL': '/bin/bash', 05:16:38 INFO - 'SHLVL': '1', 05:16:38 INFO - 'TERM': 'linux', 05:16:38 INFO - 'TMOUT': '86400', 05:16:38 INFO - 'USER': 'cltbld', 05:16:38 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:38 INFO - '_': '/tools/buildbot/bin/python'} 05:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:16:38 INFO - Cleaning up... 05:16:38 INFO - Return code: 0 05:16:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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', '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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:16: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 blobuploader==1.2.4 05:16:38 INFO - Using env: {'DISPLAY': ':0', 05:16:38 INFO - 'HOME': '/home/cltbld', 05:16:38 INFO - 'LANG': 'en_US.UTF-8', 05:16:38 INFO - 'LANGUAGE': 'en_US:en', 05:16:38 INFO - 'LOGNAME': 'cltbld', 05:16:38 INFO - 'MAIL': '/var/mail/cltbld', 05:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:38 INFO - 'MOZ_NO_REMOTE': '1', 05:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:38 INFO - 'NO_EM_RESTART': '1', 05:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:38 INFO - 'PWD': '/builds/slave/test', 05:16:38 INFO - 'SHELL': '/bin/bash', 05:16:38 INFO - 'SHLVL': '1', 05:16:38 INFO - 'TERM': 'linux', 05:16:38 INFO - 'TMOUT': '86400', 05:16:38 INFO - 'USER': 'cltbld', 05:16:38 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:38 INFO - '_': '/tools/buildbot/bin/python'} 05:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:41 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:16:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:16:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:41 INFO - Downloading docopt-0.6.1.tar.gz 05:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:16:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:41 INFO - Running setup.py install for blobuploader 05:16:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:16:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:16:42 INFO - Running setup.py install for requests 05:16:42 INFO - Running setup.py install for docopt 05:16:42 INFO - Successfully installed blobuploader requests docopt 05:16:42 INFO - Cleaning up... 05:16:42 INFO - Return code: 0 05:16:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:42 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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:42 INFO - Using env: {'DISPLAY': ':0', 05:16:42 INFO - 'HOME': '/home/cltbld', 05:16:42 INFO - 'LANG': 'en_US.UTF-8', 05:16:42 INFO - 'LANGUAGE': 'en_US:en', 05:16:42 INFO - 'LOGNAME': 'cltbld', 05:16:42 INFO - 'MAIL': '/var/mail/cltbld', 05:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:42 INFO - 'MOZ_NO_REMOTE': '1', 05:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:42 INFO - 'NO_EM_RESTART': '1', 05:16:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:42 INFO - 'PWD': '/builds/slave/test', 05:16:42 INFO - 'SHELL': '/bin/bash', 05:16:42 INFO - 'SHLVL': '1', 05:16:42 INFO - 'TERM': 'linux', 05:16:42 INFO - 'TMOUT': '86400', 05:16:42 INFO - 'USER': 'cltbld', 05:16:42 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:42 INFO - '_': '/tools/buildbot/bin/python'} 05:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:42 INFO - Running setup.py (path:/tmp/pip-pgqUGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:42 INFO - Running setup.py (path:/tmp/pip-UxAsws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:42 INFO - Running setup.py (path:/tmp/pip-ThfKAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:43 INFO - Running setup.py (path:/tmp/pip-RiYgcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:43 INFO - Running setup.py (path:/tmp/pip-7oLc09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:43 INFO - Running setup.py (path:/tmp/pip-Jr49XT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:43 INFO - Running setup.py (path:/tmp/pip-ZiuyGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:43 INFO - Running setup.py (path:/tmp/pip-HvE87V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:43 INFO - Running setup.py (path:/tmp/pip-VcW7KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:43 INFO - Running setup.py (path:/tmp/pip-XQWWBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:43 INFO - Running setup.py (path:/tmp/pip-VOtHa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:43 INFO - Running setup.py (path:/tmp/pip-h7HfAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:43 INFO - Running setup.py (path:/tmp/pip-a7zvKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:43 INFO - Running setup.py (path:/tmp/pip-e3eoKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:43 INFO - Running setup.py (path:/tmp/pip-Qo1rmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:43 INFO - Running setup.py (path:/tmp/pip-8UpS2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:43 INFO - Running setup.py (path:/tmp/pip-ezsfE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:43 INFO - Running setup.py install for manifestparser 05:16:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Running setup.py install for mozcrash 05:16:44 INFO - Running setup.py install for mozdebug 05:16:44 INFO - Running setup.py install for mozdevice 05:16:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Running setup.py install for mozfile 05:16:44 INFO - Running setup.py install for mozhttpd 05:16:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Running setup.py install for mozinfo 05:16:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Running setup.py install for mozInstall 05:16:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:44 INFO - Running setup.py install for mozleak 05:16:44 INFO - Running setup.py install for mozlog 05:16:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Running setup.py install for moznetwork 05:16:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Running setup.py install for mozprocess 05:16:45 INFO - Running setup.py install for mozprofile 05:16:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Running setup.py install for mozrunner 05:16:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Running setup.py install for mozscreenshot 05:16:45 INFO - Running setup.py install for moztest 05:16:45 INFO - Running setup.py install for mozversion 05:16:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:45 INFO - Cleaning up... 05:16:45 INFO - Return code: 0 05:16:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:45 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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:45 INFO - Using env: {'DISPLAY': ':0', 05:16:45 INFO - 'HOME': '/home/cltbld', 05:16:45 INFO - 'LANG': 'en_US.UTF-8', 05:16:45 INFO - 'LANGUAGE': 'en_US:en', 05:16:45 INFO - 'LOGNAME': 'cltbld', 05:16:45 INFO - 'MAIL': '/var/mail/cltbld', 05:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:45 INFO - 'MOZ_NO_REMOTE': '1', 05:16:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:45 INFO - 'NO_EM_RESTART': '1', 05:16:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:45 INFO - 'PWD': '/builds/slave/test', 05:16:45 INFO - 'SHELL': '/bin/bash', 05:16:45 INFO - 'SHLVL': '1', 05:16:45 INFO - 'TERM': 'linux', 05:16:45 INFO - 'TMOUT': '86400', 05:16:45 INFO - 'USER': 'cltbld', 05:16:45 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:45 INFO - '_': '/tools/buildbot/bin/python'} 05:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:46 INFO - Running setup.py (path:/tmp/pip-0v3a2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:46 INFO - Running setup.py (path:/tmp/pip-r3nPS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:46 INFO - Running setup.py (path:/tmp/pip-60Z82M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:46 INFO - Running setup.py (path:/tmp/pip-tHBaKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:46 INFO - Running setup.py (path:/tmp/pip-vsb_4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:46 INFO - Running setup.py (path:/tmp/pip-sauN_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:46 INFO - Running setup.py (path:/tmp/pip-kx1irH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:46 INFO - Running setup.py (path:/tmp/pip-Ss2kXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:46 INFO - Running setup.py (path:/tmp/pip-VKK9Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:46 INFO - Running setup.py (path:/tmp/pip-V8dTBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:46 INFO - Running setup.py (path:/tmp/pip-wOT6D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:46 INFO - Running setup.py (path:/tmp/pip-SMiuYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:46 INFO - Running setup.py (path:/tmp/pip-d8JK5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:46 INFO - Running setup.py (path:/tmp/pip-v5pfLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:47 INFO - Running setup.py (path:/tmp/pip-cTf5b_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:47 INFO - Running setup.py (path:/tmp/pip-R1TlO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:47 INFO - Running setup.py (path:/tmp/pip-iGxkMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:49 INFO - Downloading blessings-1.6.tar.gz 05:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:16:49 INFO - Installing collected packages: blessings 05:16:49 INFO - Running setup.py install for blessings 05:16:49 INFO - Successfully installed blessings 05:16:49 INFO - Cleaning up... 05:16:49 INFO - Return code: 0 05:16:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:16:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:49 INFO - Reading from file tmpfile_stdout 05:16:49 INFO - Current package versions: 05:16:49 INFO - argparse == 1.2.1 05:16:49 INFO - blessings == 1.6 05:16:49 INFO - blobuploader == 1.2.4 05:16:49 INFO - docopt == 0.6.1 05:16:49 INFO - functools32 == 3.2.3-2 05:16:49 INFO - jsonschema == 2.5.1 05:16:49 INFO - manifestparser == 1.1 05:16:49 INFO - mozInstall == 1.12 05:16:49 INFO - mozcrash == 1.0 05:16:49 INFO - mozdebug == 0.1 05:16:49 INFO - mozdevice == 0.49 05:16:49 INFO - mozfile == 1.2 05:16:49 INFO - mozhttpd == 0.7 05:16:49 INFO - mozinfo == 0.9 05:16:49 INFO - mozleak == 0.1 05:16:49 INFO - mozlog == 3.4 05:16:49 INFO - moznetwork == 0.27 05:16:49 INFO - mozprocess == 0.24 05:16:49 INFO - mozprofile == 0.28 05:16:49 INFO - mozrunner == 6.13 05:16:49 INFO - mozscreenshot == 0.1 05:16:49 INFO - mozsystemmonitor == 0.3 05:16:49 INFO - moztest == 0.8 05:16:49 INFO - mozversion == 1.4 05:16:49 INFO - psutil == 3.1.1 05:16:49 INFO - requests == 1.2.3 05:16:49 INFO - wsgiref == 0.1.2 05:16:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:49 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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:49 INFO - Using env: {'DISPLAY': ':0', 05:16:49 INFO - 'HOME': '/home/cltbld', 05:16:49 INFO - 'LANG': 'en_US.UTF-8', 05:16:49 INFO - 'LANGUAGE': 'en_US:en', 05:16:49 INFO - 'LOGNAME': 'cltbld', 05:16:49 INFO - 'MAIL': '/var/mail/cltbld', 05:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:49 INFO - 'MOZ_NO_REMOTE': '1', 05:16:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:49 INFO - 'NO_EM_RESTART': '1', 05:16:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:49 INFO - 'PWD': '/builds/slave/test', 05:16:49 INFO - 'SHELL': '/bin/bash', 05:16:49 INFO - 'SHLVL': '1', 05:16:49 INFO - 'TERM': 'linux', 05:16:49 INFO - 'TMOUT': '86400', 05:16:49 INFO - 'USER': 'cltbld', 05:16:49 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:49 INFO - '_': '/tools/buildbot/bin/python'} 05:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:16:49 INFO - Cleaning up... 05:16:49 INFO - Return code: 0 05:16:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:50 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 0x26411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, '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': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:16:50 INFO - Using env: {'DISPLAY': ':0', 05:16:50 INFO - 'HOME': '/home/cltbld', 05:16:50 INFO - 'LANG': 'en_US.UTF-8', 05:16:50 INFO - 'LANGUAGE': 'en_US:en', 05:16:50 INFO - 'LOGNAME': 'cltbld', 05:16:50 INFO - 'MAIL': '/var/mail/cltbld', 05:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:50 INFO - 'MOZ_NO_REMOTE': '1', 05:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:50 INFO - 'NO_EM_RESTART': '1', 05:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:50 INFO - 'PWD': '/builds/slave/test', 05:16:50 INFO - 'SHELL': '/bin/bash', 05:16:50 INFO - 'SHLVL': '1', 05:16:50 INFO - 'TERM': 'linux', 05:16:50 INFO - 'TMOUT': '86400', 05:16:50 INFO - 'USER': 'cltbld', 05:16:50 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:50 INFO - '_': '/tools/buildbot/bin/python'} 05:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:16:50 INFO - Cleaning up... 05:16:50 INFO - Return code: 0 05:16:50 INFO - Running post-action listener: _resource_record_post_action 05:16:50 INFO - Running post-action listener: _start_resource_monitoring 05:16:50 INFO - Starting resource monitoring. 05:16:50 INFO - [mozharness: 2017-02-16 13:16:50.273190Z] Finished create-virtualenv step (success) 05:16:50 INFO - [mozharness: 2017-02-16 13:16:50.273656Z] Running install step. 05:16:50 INFO - Running pre-action listener: _resource_record_pre_action 05:16:50 INFO - Running main action method: install 05:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:50 INFO - Reading from file tmpfile_stdout 05:16:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:16:50 INFO - Reading from file tmpfile_stdout 05:16:50 INFO - Output received: 05:16:50 INFO - Usage: mozinstall [options] installer 05:16:50 INFO - Options: 05:16:50 INFO - -h, --help show this help message and exit 05:16:50 INFO - -d DEST, --destination=DEST 05:16:50 INFO - Directory to install application into. [default: 05:16:50 INFO - "/builds/slave/test"] 05:16:50 INFO - --app=APP Application being installed. [default: firefox] 05:16:50 INFO - mkdir: /builds/slave/test/build/application 05:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:17:08 INFO - Reading from file tmpfile_stdout 05:17:08 INFO - Output received: 05:17:08 INFO - /builds/slave/test/build/application/firefox/firefox 05:17:08 INFO - Running post-action listener: _resource_record_post_action 05:17:08 INFO - [mozharness: 2017-02-16 13:17:08.216385Z] Finished install step (success) 05:17:08 INFO - [mozharness: 2017-02-16 13:17:08.216662Z] Running run-tests step. 05:17:08 INFO - Running pre-action listener: _resource_record_pre_action 05:17:08 INFO - Running main action method: run_tests 05:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:08 INFO - Python 2.7.3 05:17:08 INFO - Return code: 0 05:17:08 INFO - grabbing minidump binary from tooltool 05:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:08 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 0x296b540>, '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 0x28dff70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e0400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:17:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:17:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:17:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:08 INFO - Return code: 0 05:17:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:17:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:08 INFO - ENV: RUST_BACKTRACE is now 1 05:17:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:17:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:08 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/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:17:08 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/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:17:08 INFO - Using env: {'DISPLAY': ':0', 05:17:08 INFO - 'HOME': '/home/cltbld', 05:17:08 INFO - 'LANG': 'en_US.UTF-8', 05:17:08 INFO - 'LANGUAGE': 'en_US:en', 05:17:08 INFO - 'LOGNAME': 'cltbld', 05:17:08 INFO - 'MAIL': '/var/mail/cltbld', 05:17:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:08 INFO - 'MOZ_NO_REMOTE': '1', 05:17:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:08 INFO - 'NO_EM_RESTART': '1', 05:17:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:08 INFO - 'PWD': '/builds/slave/test', 05:17:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:08 INFO - 'RUST_BACKTRACE': '1', 05:17:08 INFO - 'SHELL': '/bin/bash', 05:17:08 INFO - 'SHLVL': '1', 05:17:08 INFO - 'TERM': 'linux', 05:17:08 INFO - 'TMOUT': '86400', 05:17:08 INFO - 'USER': 'cltbld', 05:17:08 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601', 05:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:08 INFO - '_': '/tools/buildbot/bin/python'} 05:17:08 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/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:17:08 INFO - mozversion application_buildid: 20170216121511 05:17:08 INFO - mozversion application_changeset: 8c8b54b13be7ec12cb8e104b772162a80b524497 05:17:08 INFO - mozversion application_display_name: Nightly 05:17:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:08 INFO - mozversion application_name: Firefox 05:17:08 INFO - mozversion application_remotingname: firefox 05:17:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:17:08 INFO - mozversion application_vendor: Mozilla 05:17:08 INFO - mozversion application_version: 54.0a1 05:17:08 INFO - mozversion platform_buildid: 20170216121511 05:17:08 INFO - mozversion platform_changeset: 8c8b54b13be7ec12cb8e104b772162a80b524497 05:17:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:17:08 INFO - mozversion platform_version: 54.0a1 05:17:08 INFO - using testdate: 1487251028 05:17:08 INFO - actual date: 1487251028 05:17:08 INFO - starting webserver on 'localhost:60691' 05:17:08 INFO - SUITE-START | Running 2 tests 05:17:08 INFO - TEST-START | dromaeo_css 05:17:08 INFO - operating with platform_type : linux_ 05:17:08 INFO - Initialising browser for dromaeo_css test... 05:17:08 INFO - TEST-INFO | started process 24904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvhMJLx/profile http://localhost:60691/getInfo.html) 05:17:11 INFO - PROCESS | 24904 | Iterating over profile /tmp/tmpvhMJLx/profile 05:17:12 INFO - PROCESS | 24904 | __metrics Screen width/height:1600/1200 05:17:12 INFO - PROCESS | 24904 | colorDepth:24 05:17:12 INFO - PROCESS | 24904 | Browser inner width/height: 1024/697 05:17:12 INFO - PROCESS | 24904 | __metrics 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/places.sqlite-wal = Thu Feb 16 2017 05:17:12 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/content-prefs.sqlite = Thu Feb 16 2017 05:17:10 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/mimeTypes.rdf = Thu Feb 16 2017 05:17:12 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/compatibility.ini = Thu Feb 16 2017 05:17:09 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/extensions = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/directoryLinks.json = Thu Feb 16 2017 05:17:11 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/bookmarkbackups = Thu Feb 16 2017 05:17:10 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/cache2 = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/localstore.rdf = Thu Feb 16 2017 05:16:25 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/cookies.sqlite = Thu Feb 16 2017 05:17:12 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/places.sqlite = Thu Feb 16 2017 05:17:12 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/extensions.json = Thu Feb 16 2017 05:17:11 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/addons.json = Thu Feb 16 2017 05:17:11 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/safebrowsing = Thu Feb 16 2017 05:17:12 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/permissions.sqlite = Thu Feb 16 2017 05:17:09 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/key3.db = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/startupCache = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/pluginreg.dat = Thu Feb 16 2017 05:17:11 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | Stat failure 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/cert8.db = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/places.sqlite-shm = Thu Feb 16 2017 05:17:09 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/prefs.js = Thu Feb 16 2017 05:16:25 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/minidumps = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/.parentlock = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/extensions.ini = Thu Feb 16 2017 05:17:09 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/thumbnails = Thu Feb 16 2017 05:17:10 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/gmp = Thu Feb 16 2017 05:17:09 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/secmod.db = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/sessionCheckpoints.json = Thu Feb 16 2017 05:17:12 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/frequencyCap.json = Thu Feb 16 2017 05:17:11 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/crashes = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/user.js = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:12 INFO - PROCESS | 24904 | No birth date. Using mtime. 05:17:12 INFO - PROCESS | 24904 | Using date: /tmp/tmpvhMJLx/profile/blocklist.xml = Thu Feb 16 2017 05:17:08 GMT-0800 (PST) 05:17:13 INFO - PROCESS | 24904 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:17:13 INFO - PROCESS | 24904 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:17:14 INFO - PROCESS | 24904 | console.error: PushService: 05:17:14 INFO - PROCESS | 24904 | stateChangeProcessEnqueue: Error transitioning state 05:17:14 INFO - PROCESS | 24904 | UnknownError 05:17:14 INFO - TEST-INFO | 24904: exit 0 05:17:14 INFO - Browser initialized. 05:17:14 INFO - Running cycle 1/1 for dromaeo_css test... 05:17:14 INFO - TEST-INFO | started process 24989 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvhMJLx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:26 INFO - PROCESS | 24989 | RSS: Main: 153972736 05:17:26 INFO - PROCESS | 24989 | 05:20:28 INFO - PROCESS | 24989 | Cycle 1(1): loaded http://localhost:60691/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60691/tests/dromaeo/cssquery-ext.html) 05:20:30 INFO - PROCESS | 24989 | RSS: Main: 306225152 05:20:30 INFO - PROCESS | 24989 | 05:22:17 INFO - PROCESS | 24989 | ************************* 05:22:17 INFO - PROCESS | 24989 | A coding exception was thrown and uncaught in a Task. 05:22:17 INFO - PROCESS | 24989 | 05:22:17 ERROR - PROCESS | 24989 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:22:17 INFO - PROCESS | 24989 | Full stack: 05:22:17 INFO - PROCESS | 24989 | ************************* 05:23:32 INFO - PROCESS | 24989 | Cycle 1(1): loaded http://localhost:60691/tests/dromaeo/cssquery-ext.html (next: http://localhost:60691/tests/dromaeo/cssquery-jquery.html) 05:23:33 INFO - PROCESS | 24989 | RSS: Main: 248360960 05:23:33 INFO - PROCESS | 24989 | 05:26:35 INFO - PROCESS | 24989 | Cycle 1(1): loaded http://localhost:60691/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60691/tests/dromaeo/cssquery-mootools.html) 05:26:35 INFO - PROCESS | 24989 | RSS: Main: 213622784 05:26:35 INFO - PROCESS | 24989 | 05:29:37 INFO - PROCESS | 24989 | Cycle 1(1): loaded http://localhost:60691/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60691/tests/dromaeo/cssquery-prototype.html) 05:29:38 INFO - PROCESS | 24989 | RSS: Main: 184791040 05:29:38 INFO - PROCESS | 24989 | 05:32:41 INFO - PROCESS | 24989 | Cycle 1(1): loaded http://localhost:60691/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60691/tests/dromaeo/cssquery-yui.html) 05:32:41 INFO - PROCESS | 24989 | RSS: Main: 186970112 05:32:41 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | Cycle 1(1): loaded http://localhost:60691/tests/dromaeo/cssquery-yui.html (next: http://localhost:60691/tests/dromaeo/cssquery-dojo.html) 05:35:44 INFO - PROCESS | 24989 | RSS: Main: 293113856 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | __start_tp_report 05:35:44 INFO - PROCESS | 24989 | _x_x_mozilla_page_load 05:35:44 INFO - PROCESS | 24989 | _x_x_mozilla_page_load_details 05:35:44 INFO - PROCESS | 24989 | |i|pagename|runs| 05:35:44 INFO - PROCESS | 24989 | |0;dojo.html;3138.1526987713314;3162.0988018414755;3203.9839800800987;3204.2149673330036;3215.4694475411557;5134.563562097217;5194.324737784083;5266.63133580649;5379.704116273599;5381.677099374036;5286.550643195333;5288.365396152458;5313.335833020872;5323.547498462625;5392.622516423852;4793.832215872445;4845.733484658343;4852.927206091911;4865.148598995162;4921.483244259355;4946.084974279752;4968.205087186051;4994.250862370655;5010.248462730601;5015.724135172564;4817.325574419584;4865.6107511399005;4866.051120031595;4868.829590964335;4874.098291816008;4837.419509658828;4895.902081958377;4903.41159060915;4986.326845875797;4993.700377977333;4875.398182443569;4886.633502487292;4902.975485122587;4909.8772530686665;4958.826441074545;5389.703566303851;5437.102878025121;5438.592105592096;5440.836774896759;5450.018996580614;5560.304961879765;5623.690697011703;5625.971870140623;5693.28833895763;5704.315482142128;5611.410801865786;5715.857103572403;5728.25532680753;5731.054376027951;5733.140028995684;5283.101872681654;5305.363356397218;5355.7857685692525;5357.624966252364;5359.490848369399;5734.741936612862;5784.681842498703;5791.739371728282;5796.855078623068;5829.183914252008;5536.889262214733;5552.91670624941;5560.471755183251;5575.609707320448;5583.944160558423;5756.33802112754;5757.510611598061;5760.51035661965;5763.682997435098;5765.07758758605;5690.231818704505;5721.799737009184;5770.538356931435;5801.8549536261935;5809.709514524341;5802.100674395476;5808.82573522795;5824.213731146241;5827.388124246978;5829.591928565044;4453.55464453552;4462.598366146997;4465.575770301816;4469.709468884512;4494.235979883428;3961.782101984361;3980.283548961215;3985.043589538548;4009.278329900588;4021.416894550274;5222.190560463135;5260.789568417221;5262.631615786935;5321.840344789663;5328.653637513549;5714.628549144292;5750.396208398141;5791.218185544897;5861.296644402613;5873.295204575393;5502;5502.2296878437055;5504.91742623861;5533.612647114744;5548.86127846799;5009.674371165863;5028.34631497903;5033.068882256721;5035;5072.695638261643;5802.709864506842;5828.6211396259105;5839.240898683144;6079.996800527952;6092.817215483543;5628.634138780966;5654.660720356792;5665.461781130786;5680.9774240635425;5713.542916566747;5878.2358293421585;5923.970380148072;5927.7036148191555;5982.551308651956;6004.669743164168;5662.971685141548;5665.66006039639;5666.546676265972;5666.603337766482;5685.488306052475;5609.6073274872;5655.123455864347;5660.009498337857;5758.25142731459;5768.134779783066;5501.614886957875;5516.613837031369;5579.107342825128;5586.636868603528;5631.324241091095;5611.663300202001;5672.262605861211;5673;5673.8297851064535;5714.857128571819;5539.446055394428;5595.720213989365;5599.944000559943;5603.495685388335;5607.130894711327;5460.672359658433;5502.80740174092;5588.133839254922;5645.379008309164;5659.9151012735665;5294.841154765363;5329.520343169133;5333.813316533902;5375.3280839895015;5558.916616250679 05:35:44 INFO - PROCESS | 24989 | |1;ext.html;534.9125991021111;598.7036416973598;604.5465900574569;604.6674329118985;605.4399680295726;30481.39037219257;31104;33449.83275083621;33458.33083338332;33459.49810752841;43313.13373732531;44809.55190448095;44858.32712509307;49228;49268.26097608539;9258.67594634188;9413.905860941371;9416.858747118764;9419.858702119474;9423.34036617437;7586.962065189667;7770.4172187085915;9302.790637217153;9411.576479058425;9422.858657120149;10778.676639700783;11024.393658348788;11026.779464410707;11442.199046066737;11445.942770286138;11149.21955463118;11206.103511719084;11220.495077721522;11228.775424491547;11236.66290011301;10897.945510272399;10977.890221097767;10991.890081099167;10994.28537145083;10998.285111467729;42636.14727705428;42676.14647707059;42798.57201428008;42823;42985.570144298785;3722.646348596879;3811.9809400953095;3835.7506762060375;3839.9808000960097;3896.8051597420017;26659.46681066387;28640.283992900346;28650;28716.712832871614;28828.567571486445;4109.527404348508;4164.729292595972;4266.402703621496;4451.5103338632725;4466.017476155241;239375;240380;241371;242469;242850;49507.75246123746;49683.25475117876;49918;50247.49752502501;50260.74869625628;48068.58799710037;48396.27405588919;48462.75768621134;48470.757646212245;48489.515104849204;15402.84597154014;15692.92153539225;15787.526374208792;15801.841981580266;15803.051816891024;11956.163068585116;12117.87882121185;12118.394080296126;12124.696882578006;12127.878721212852;14985.925070374578;15299.082055076731;17033.659326813642;17510.562235944202;17546.210420531108;6638.444198983426;6808.38724514796;6843.281455447206;7026.664933662588;7042.394314103712;836.9372297077743;860.1699360117517;860.5396113079472;877.4954401219325;888.3958907942532;28810.42379152447;29625.85187074094;30063.549046763877;30413;30450.390992180033;1038.657243109772;1043.5929987304958;1056.0442799266675;1063.5639387850945;1064.5369264370024;15334.23328833554;16343.836561634469;16409.507714768577;16424.589385265226;16561.83438165603;470544.53836963343;473751;474516;475412;476355;61434.38565614376;61753.38246617566;61830.381696183365;62014;62160.067598986054;1303.2897071096274;1362.8500864904852;1369.0690330575305;1375.5460697969845;1379.8620137986322;28945;30258.84870575633;30313.848430757706;30832.383352333276;30854.53718194274;52393.7380313096;52866.51903764516;52913;52971.73514132405;53023.73488132535;290575;292627;292798;293230;294912;26018;26081.217563473125;26359;27416.58875116835;27742.306442338297;35913.46129808003;36119.81940090283;44952.775236124915;46177.76911115423;46240;35738;37752.81123594364;39095.02262443462;39108.60891391164;39801.60198397979;37569.275160440215;38666.42000369941;39310.60689393069;40192.59807401888;40519.59480405158;39077.8046109779;40140.1971960565;40147.397789032606;40180.95476130967;40476.59523404846;33291.500627490124;34137.658623413445;34706.479402809484;34859.82570087133;34994.300113998084;25990.610140847522;26005.479890402465;26299.21102366932;26640.866795665897;26937.567944562077 05:35:44 INFO - PROCESS | 24989 | |2;jquery.html;403.66697474583475;407.14499550943026;424.4672935465991;428.6506497204778;444.67760873366814;18700.90649546752;19163.945982970912;20183.798162018375;20388.796112038875;20589.27937522187;20159.395218143443;20322.390328290174;20440.08019639116;20475.488112797186;20669.483262918435;5713.114467257582;5990.9101363479585;6018.217631707894;6018.277806663206;6029.547783916202;5803.7968671096305;5891.587588868781;6028.1861948636815;6142;6142.0786881967615;5897.085951677497;5910.615809972338;5912.88174236515;5913.615614985034;5915.940840591604;5910.1134829775665;5919.556033297498;5921.674307913063;6152.907706384408;6206.348333425016;6135.6625385603775;6154.230721159855;6155.784547540859;6158.753649854;6163.445289923883;22086.226982055712;24038.75961240383;24064.278071657878;24128;24248.393790155213;2535.657686212356;2650.3374156460886;2652.350174207312;2664.813463057587;2704.323919020245;10411.375317480899;10459.895401046044;10728.570857165781;11035.062019728333;11082.667519974413;1891.6216756648726;1940.388777535072;1948.4349538633778;1954.5211423201406;1957.8727382720078;83785;84142.57928710378;84639.15360846375;85589;86614.5669271656;11141.832872506919;11270.605528806616;11602.883971160349;11811.291322520676;11900.523979040914;16797.832021679627;16900.32398704038;16978.15109244533;17067.146642667816;17082.829171708123;6703.363180497942;6873.896891546531;7608.125065617414;8133.677112257088;8232.34141268697;7512.849743005109;7524.811879703051;7583.165851756303;7784.84430311402;7786.727464538714;4897.7795999180125;5006.123928312531;5029;5158.458361872024;5207.765650545734;1926.6032126708023;2033.6136134134467;2044.2231951858198;2110.7889211079078;2121.2787652198326;904.6426661468814;921.1985572551923;921.8755468011733;922.0226559846584;929.5677509957902;18775.436736897915;20056.197752090044;20235.595288094155;20447.28434504785;20459.897700511403;914.4020798940313;922.3681777982104;945.2626950978248;946.2997381937455;951.9857202141974;10258.538365773558;10432.070067037623;11177.776444471065;11271.38007409584;11300.095992320594;529036.741509556;531155;531483;536456;542774;99193;99958.01973072645;103037;103618.75815457813;104331;1012.2104758288547;1021.7803172317987;1050.76883085721;1053.031211285619;1053.4574694032578;16521;16636.66726665484;17174;17518.38685645996;17553.1223438825;42679.57320426756;43293.783531083405;43364.34953475637;43605.90985225268;44478.332825007004;57743.422565773806;58314;58376.416235837096;58888.411115890005;59375.40624593699;6481.708323125565;8624.18070283322;8725.781855453664;8759.343049271203;9024.684136055206;12673.429695663533;13605.387757550736;13765.449382024606;13766.93116534411;13817.378217980015;6312.684365781784;11287.7742445149;11785.705357366134;11848.466818992994;12007.339596321937;11710.297382157098;11927.165098443025;12132.393380330772;12517.311547864954;12524.49902003946;11839.112066594867;11875.82186267224;11881.287122772315;12049.33728644898;12287.75424491523;7450.292222238879;7873.448858579844;7972.92027079745;8417.789555261168;8715.607797649247;8660.307175426076;12000.819987700366;12020.939895300467;12070.396480176132;12143.332116733665 05:35:44 INFO - PROCESS | 24989 | |3;mootools.html;1288.9484420623176;1428.0574820618388;1430.5493769462619;1430.7567713488713;1436.5474875414247;7538.811529711753;8371.539565323905;8531.914680853197;8581.184787445198;8787.340949428803;8568.65725370986;8666.73999780006;8670.306375489961;8696.21734043936;8700.086490918457;1882.4070417818411;2225.4213904384897;2246.539459410825;2262.954740905181;2264.852784569006;1930.0446279091868;2209.9226527071555;2265.320403878838;2272.522770218256;2284.4403121235237;2176.4341271269504;2184.6722991551237;2204.261572373257;2205.911763529465;2208.3595757230382;2116.6719158530377;2125.1818050050765;2127.680847872816;2131.925382611601;2133.8826364549946;2120.6606942889216;2132.445564153316;2208.447888027993;2212.9889350553144;2217.5121473275854;8702.30381569473;8725.563721813884;8737.475751454933;8739.169778870997;8790.560471976376;4994.275830004629;5126.154184559543;5126.538611524943;5134.64057515974;5155.587552995751;3084.2443601317595;3217.2278653123317;3281.96718032819;3285.4578994465883;3302.3725492156655;1298.8441387033522;1321.8124004772117;1353.1610401551102;1358.7363751710905;1402.7895815627635;11290.887091129149;11360.261582997162;11372.48823802931;11379.829302560469;11394.202405831513;4355.651547876226;4503.234450143484;4561.452625684939;4617.307403889444;4648.302754586772;10236.53935572901;10237.74405639865;10240.692779216635;10241.84637230442;10256.333338332985;5387.892242155213;5391.32608423947;5391.595630327741;5393.164059570773;5395.7841686332895;4677.76611169447;4759.666823322404;4807.975960120177;4839.491853355417;4855.271709243641;3820.8662696805477;3879.165979314461;3901.4147877817986;3950.5654378018394;4008.6191811777835;1255.6923553729396;1259.80473026681;1263.5072321794507;1287.2212311551564;1292.4700872642172;822.8066404394963;828.4821986258588;846.0363198415444;866.9739907802776;873.2708188662411;5057.241413787961;5205.869853253624;5265.447128051576;5304.575633949275;5306.495882891119;606.5541826757329;623.4793947054261;624.194788722552;626.9028300613412;627.1721327847198;3268.9182770430452;3314.784539005005;3328.1679580104974;3343.8160901150673;3452.3095380923914;9972.950135249423;10378.948105259426;10398;10409.583616655249;10420.843687344695;10154.593816247267;10308;10337.0696637303;10343.844842327371;10349.172066234683;7741.148473667892;7879.448438609243;7924.088729796032;7946.7615971520945;7989.840203195903;5247.606429517725;5252.9737351313;5258.737063146751;5343.198520221998;5400.62195646301;8958.193762561401;9282.21101206405;9296.907030929604;9306.72079837606;9394.342396032212;8389.95805020971;9216.539173041187;9379.108984646447;9472.621095156117;9550.665726699532;6554.377334153248;7116.181639111362;7275.236100209613;7328.670209840677;7496.475246732676;7897.368210543028;8207.548584827653;8279.710210142615;8283.08886022525;8320.417570769989;7489.550626962399;7517.173110957681;7544.094708634999;7578.317951384402;7746.457747957589;7250.129984401906;7319.634018298957;7320.487565870338;7337.046183996046;7340.779776606711;6087.847803804941;6093.451589356801;6095.481884039729;6152.692365381624;6175;4781.665283430266;4865.464798872192;4908.680935739166;4922.237053256751;4994.1260279450225;4891.192953162688;4943.305898285515;5047.823326183566;5066.746662666926;5122.743862806919 05:35:44 INFO - PROCESS | 24989 | |4;prototype.html;382.41872354021905;383.18190662934643;389.8460108257237;396.24317553472866;404.71063189819284;5266.973665131673;5333.866653333668;5338.786448542059;5357.30355053843;5384.165454354572;5109.718965456898;5246.235729407809;5325.7869685212545;5340.198970154479;5340.385855626604;4145.129522800215;4184.100418410041;4206.263903816835;4222.535521092677;4242.533321334666;4225.260579398593;4229.978850105746;4242.621148126856;4243.090609225846;4255.553166917475;4120.890056524158;4162.625363717265;4186.832526698943;4215.683823713219;4221.662267018646;4156.667466602695;4177.394277829698;4182.769947652878;4219.312275089957;4229.471316085489;3753.9249215015548;3766.9811650941842;3772.5472943246714;3797.5063241778666;3801.2207497462955;5172.250023746536;5189.273501709764;5224.92162617561;5241.659292145999;5241.86895327616;4481.5070342262325;4663.437748354306;4674.345591617176;4686.133065382915;4698.624110071186;5473.8357849264585;5548;5554.666719996772;5556.611037227396;5625.156226566007;4022.612198791412;4037.212743515016;4051.1290072634524;4072.3891416287497;4080.9387859182134;5385.246065550826;5546.99999999996;5599.804006859781;5707.571932105108;5763.827085187451;3985.20295940813;4062.857799976987;4065.2276067547073;4068.3287257602165;4097.549269580344;4139.97930010348;4142.772147531915;4145.66834653227;4150.398192262165;4172.937405938914;4537.523560026149;4538.205813982539;4639.536046395334;4768.856934291976;4783.330333753277;5555.138953462219;5567.721613919288;5576.633724737465;5587.32952045749;5626.156076588462;1368.3158420789605;1385.0512399006714;1386.9791953120712;1391.3460673483446;1397.5178563395611;897.4747524123213;961.5672947173659;968.9127978481971;972.3339512434005;979.7550612346913;5129.410117836422;5151.536361727388;5164;5167.276581278624;5167.974160129175;5402.621816472888;5415.296011518478;5420.34955805298;5442.700651464128;5456.836294911159;5028.79884804611;5056.82301119459;5134.9690492962845;5173.430922598511;5240.4540660505245;2859.7702987715484;2896.7392934635564;2899.144752298077;2910.039686903317;2915.6501219853753;5698.886022279531;5705.2012718219485;5709.543236541067;5721.313442386941;5729.5702822288495;5395.86863189046;5453.509184173444;5554.583581186772;5602.627356297733;5649.858753531194;5703.8859222816145;5717.685527295956;5721.427857214246;5731.426857314235;5755.9424405756245;5307;5324.520793128637;5449.7002664852225;5482.39693633692;5496.340439147168;5345.717027913351;5369.731513424391;5379.381371142369;5439.525016117736;5474.315710536183;5561.332640083216;5662.150677398423;5674.659520428788;5683.801066962802;5724.656520608777;4036.535798383106;4055.6349928506575;4059.3505039193587;4062.1469491405956;4062.471878655756;5632.295963004624;5647.745851436777;5689.805140920453;5693.259876216065;5738.770449181986;5266.657667251617;5523.917141242965;5547.750351234124;5572.108462645957;5588.6527658382765;5507.476789704971;5532.28080349552;5537.6709589698985;5545.72271386421;5550.306211723535;5315.813946512008;5569.498745112959;5571.439996800902;5576.470235327637;5588.020817724995;5544.63959842609;5548.722563871871;5565.666060036411;5576.609637325349;5597.720113994365;5459.454054594509;5461.918071228855;5469.924905945097;5473.8357849264585;5479 05:35:44 INFO - PROCESS | 24989 | |5;yui.html;702.964851757412;763.3435245688703;795.5783434779565;829.8672212446008;856.8200677857648;14106.717865642693;14486.927565362184;14513.346899389526;14516.491922782705;14544.272786360667;14787.112773233614;14850.257487125627;14977.251137443138;15037.849621503781;15040;764.5603777827757;781.9218078192192;782.6517199846071;791.2443616346376;792.6195426195429;795.9482633628825;798.0702481608919;798.2655956519986;798.4889670610813;799.312591171592;834.1200033964195;834.157500924068;834.470111479209;836.1011912194415;836.506461187899;823.423603477565;829.4401279136558;830.7424698343533;832.0930186097183;832.633641197871;603.5594016368032;605.7153138025121;606.9484093852028;607.068150389155;608.0210860514568;14444.711105777931;14445;14520.419183232658;14997.475088371964;15510;14107.78838317426;14639.121652700765;14680.779788303184;14780;14847.777283340758;12042.63872083839;12262.646760400019;12264.938675306656;12533;12543.686407839787;3679.6688298053036;3718.1262403335195;3727.14275716584;3773.3773927301963;3773.962260377416;73339.26660733431;73440.26559734388;75548;76732.61633691852;78250.21749782543;10676.359418434764;10679.412632305297;10725;10790;10969.341839489496;21525.354239372842;21574.137034518757;21770;21851.344459666234;21998.560028799333;7443.627818609048;7478.70085196586;7484.1393239777035;7495.138059123271;7770.844583108306;4062.8171732272117;4131.29767939451;4193.832246710097;4204.768737719455;4211.936820947688;3603.477495763152;3653.707703383723;3666.8716594919583;3686.09690625793;3687.7418580699095;622.9937700623026;626.9028300613321;630.5995692735146;636.6689321552767;639.8304449320933;719.9388052015532;722.8518153778463;738.4350971506801;761.105700801567;774.8682723936944;10391.272610917315;10423.21825863048;10429.68710938673;10446.996563095308;10576.365418074785;679.5413096160072;680.1701923653135;684.4797953555334;695.0720787748312;695.5652717051843;4609.216433206363;4995.725235112031;5023.899522009539;5032.295478632995;5043.747812609355;74873.62563187149;75023;75869.51631046119;75944.62027689937;77196;64975.350246497874;65711.34288657052;65750.67124664345;65898.34101659017;65909.34090659033;56708.149377759364;56741;56885.43114568884;57097.71451142718;57530.42469575251;9251.629934802533;9736.561854716412;9864.901350986398;9878.4566848824;9882.505874706092;40463.797681011405;41873.58126418697;42103.36844947267;42432.575674242864;42971.78514107409;49939.50060499348;51915.740421297654;52720.73639631777;52780;53636;10223.28437009432;10484.790304194026;10575.62985295511;10596.31123976939;11090.112790976864;12673.556425525063;13163.881070109148;13476.326183690971;13830.37763300674;14084.507042253472;12002.339871307162;12577.87422125767;12712.3008234548;13252.072354935062;13425.395857186255;11835.11236657237;12118.394080296126;12215.572454964034;12375.504979800708;12643.747125057263;11570.19008669385;12158.817617735564;12385.442655080362;12897.355132243163;12907.354632268161;9047.773805654911;9450.763730906783;9559.187469065208;9566.808663826823;9790.608375664893;8889.777755556162;9117.407368521024;9630.807383852423;9672.70981870545;9673.661421850202 05:35:44 INFO - PROCESS | 24989 | __end_tp_report 05:35:44 INFO - PROCESS | 24989 | __start_cc_report 05:35:44 INFO - PROCESS | 24989 | _x_x_mozilla_cycle_collect,1617 05:35:44 INFO - PROCESS | 24989 | __end_cc_report 05:35:44 INFO - PROCESS | 24989 | __startTimestamp1487252144833__endTimestamp 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | ------- Summary: start ------- 05:35:44 INFO - PROCESS | 24989 | Number of tests: 6 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | [#0] dojo.html Cycles:165 Average:5329.34 Median:5536.89 stddev:578.56 (10.4%) stddev-sans-first:554.21 05:35:44 INFO - PROCESS | 24989 | Values: 3138.2 3162.1 3204.0 3204.2 3215.5 5134.6 5194.3 5266.6 5379.7 5381.7 5286.6 5288.4 5313.3 5323.5 5392.6 4793.8 4845.7 4852.9 4865.1 4921.5 4946.1 4968.2 4994.3 5010.2 5015.7 4817.3 4865.6 4866.1 4868.8 4874.1 4837.4 4895.9 4903.4 4986.3 4993.7 4875.4 4886.6 4903.0 4909.9 4958.8 5389.7 5437.1 5438.6 5440.8 5450.0 5560.3 5623.7 5626.0 5693.3 5704.3 5611.4 5715.9 5728.3 5731.1 5733.1 5283.1 5305.4 5355.8 5357.6 5359.5 5734.7 5784.7 5791.7 5796.9 5829.2 5536.9 5552.9 5560.5 5575.6 5583.9 5756.3 5757.5 5760.5 5763.7 5765.1 5690.2 5721.8 5770.5 5801.9 5809.7 5802.1 5808.8 5824.2 5827.4 5829.6 4453.6 4462.6 4465.6 4469.7 4494.2 3961.8 3980.3 3985.0 4009.3 4021.4 5222.2 5260.8 5262.6 5321.8 5328.7 5714.6 5750.4 5791.2 5861.3 5873.3 5502.0 5502.2 5504.9 5533.6 5548.9 5009.7 5028.3 5033.1 5035.0 5072.7 5802.7 5828.6 5839.2 6080.0 6092.8 5628.6 5654.7 5665.5 5681.0 5713.5 5878.2 5924.0 5927.7 5982.6 6004.7 5663.0 5665.7 5666.5 5666.6 5685.5 5609.6 5655.1 5660.0 5758.3 5768.1 5501.6 5516.6 5579.1 5586.6 5631.3 5611.7 5672.3 5673.0 5673.8 5714.9 5539.4 5595.7 5599.9 5603.5 5607.1 5460.7 5502.8 5588.1 5645.4 5659.9 5294.8 5329.5 5333.8 5375.3 5558.9 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | [#1] ext.html Cycles:180 Average:50243.27 Median:35825.73 stddev:92679.32 (258.7%) stddev-sans-first:92864.16 05:35:44 INFO - PROCESS | 24989 | Values: 534.9 598.7 604.5 604.7 605.4 30481.4 31104.0 33449.8 33458.3 33459.5 43313.1 44809.6 44858.3 49228.0 49268.3 9258.7 9413.9 9416.9 9419.9 9423.3 7587.0 7770.4 9302.8 9411.6 9422.9 10778.7 11024.4 11026.8 11442.2 11445.9 11149.2 11206.1 11220.5 11228.8 11236.7 10897.9 10977.9 10991.9 10994.3 10998.3 42636.1 42676.1 42798.6 42823.0 42985.6 3722.6 3812.0 3835.8 3840.0 3896.8 26659.5 28640.3 28650.0 28716.7 28828.6 4109.5 4164.7 4266.4 4451.5 4466.0 239375.0 240380.0 241371.0 242469.0 242850.0 49507.8 49683.3 49918.0 50247.5 50260.7 48068.6 48396.3 48462.8 48470.8 48489.5 15402.8 15692.9 15787.5 15801.8 15803.1 11956.2 12117.9 12118.4 12124.7 12127.9 14985.9 15299.1 17033.7 17510.6 17546.2 6638.4 6808.4 6843.3 7026.7 7042.4 836.9 860.2 860.5 877.5 888.4 28810.4 29625.9 30063.5 30413.0 30450.4 1038.7 1043.6 1056.0 1063.6 1064.5 15334.2 16343.8 16409.5 16424.6 16561.8 470544.5 473751.0 474516.0 475412.0 476355.0 61434.4 61753.4 61830.4 62014.0 62160.1 1303.3 1362.9 1369.1 1375.5 1379.9 28945.0 30258.8 30313.8 30832.4 30854.5 52393.7 52866.5 52913.0 52971.7 53023.7 290575.0 292627.0 292798.0 293230.0 294912.0 26018.0 26081.2 26359.0 27416.6 27742.3 35913.5 36119.8 44952.8 46177.8 46240.0 35738.0 37752.8 39095.0 39108.6 39801.6 37569.3 38666.4 39310.6 40192.6 40519.6 39077.8 40140.2 40147.4 40181.0 40476.6 33291.5 34137.7 34706.5 34859.8 34994.3 25990.6 26005.5 26299.2 26640.9 26937.6 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | [#2] jquery.html Cycles:180 Average:30851.13 Median:1554.00 stddev:88117.99 (5670.4%) stddev-sans-first:88335.52 05:35:44 INFO - PROCESS | 24989 | Values: 403.7 407.1 424.5 428.7 444.7 18700.9 19163.9 20183.8 20388.8 20589.3 20159.4 20322.4 20440.1 20475.5 20669.5 5713.1 5990.9 6018.2 6018.3 6029.5 5803.8 5891.6 6028.2 6142.0 6142.1 5897.1 5910.6 5912.9 5913.6 5915.9 5910.1 5919.6 5921.7 6152.9 6206.3 6135.7 6154.2 6155.8 6158.8 6163.4 22086.2 24038.8 24064.3 24128.0 24248.4 2535.7 2650.3 2652.4 2664.8 2704.3 10411.4 10459.9 10728.6 11035.1 11082.7 1891.6 1940.4 1948.4 1954.5 1957.9 83785.0 84142.6 84639.2 85589.0 86614.6 11141.8 11270.6 11602.9 11811.3 11900.5 16797.8 16900.3 16978.2 17067.1 17082.8 6703.4 6873.9 7608.1 8133.7 8232.3 7512.8 7524.8 7583.2 7784.8 7786.7 4897.8 5006.1 5029.0 5158.5 5207.8 1926.6 2033.6 2044.2 2110.8 2121.3 904.6 921.2 921.9 922.0 929.6 18775.4 20056.2 20235.6 20447.3 20459.9 914.4 922.4 945.3 946.3 952.0 10258.5 10432.1 11177.8 11271.4 11300.1 529036.7 531155.0 531483.0 536456.0 542774.0 99193.0 99958.0 103037.0 103618.8 104331.0 1012.2 1021.8 1050.8 1053.0 1053.5 16521.0 16636.7 17174.0 17518.4 17553.1 42679.6 43293.8 43364.3 43605.9 44478.3 57743.4 58314.0 58376.4 58888.4 59375.4 6481.7 8624.2 8725.8 8759.3 9024.7 12673.4 13605.4 13765.4 13766.9 13817.4 6312.7 11287.8 11785.7 11848.5 12007.3 11710.3 11927.2 12132.4 12517.3 12524.5 11839.1 11875.8 11881.3 12049.3 12287.8 7450.3 7873.4 7972.9 8417.8 8715.6 8660.3 12000.8 12020.9 12070.4 12143.3 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | [#3] mootools.html Cycles:180 Average:5494.35 Median:4994.20 stddev:3131.99 (62.7%) stddev-sans-first:3124.83 05:35:44 INFO - PROCESS | 24989 | Values: 1288.9 1428.1 1430.5 1430.8 1436.5 7538.8 8371.5 8531.9 8581.2 8787.3 8568.7 8666.7 8670.3 8696.2 8700.1 1882.4 2225.4 2246.5 2263.0 2264.9 1930.0 2209.9 2265.3 2272.5 2284.4 2176.4 2184.7 2204.3 2205.9 2208.4 2116.7 2125.2 2127.7 2131.9 2133.9 2120.7 2132.4 2208.4 2213.0 2217.5 8702.3 8725.6 8737.5 8739.2 8790.6 4994.3 5126.2 5126.5 5134.6 5155.6 3084.2 3217.2 3282.0 3285.5 3302.4 1298.8 1321.8 1353.2 1358.7 1402.8 11290.9 11360.3 11372.5 11379.8 11394.2 4355.7 4503.2 4561.5 4617.3 4648.3 10236.5 10237.7 10240.7 10241.8 10256.3 5387.9 5391.3 5391.6 5393.2 5395.8 4677.8 4759.7 4808.0 4839.5 4855.3 3820.9 3879.2 3901.4 3950.6 4008.6 1255.7 1259.8 1263.5 1287.2 1292.5 822.8 828.5 846.0 867.0 873.3 5057.2 5205.9 5265.4 5304.6 5306.5 606.6 623.5 624.2 626.9 627.2 3268.9 3314.8 3328.2 3343.8 3452.3 9973.0 10378.9 10398.0 10409.6 10420.8 10154.6 10308.0 10337.1 10343.8 10349.2 7741.1 7879.4 7924.1 7946.8 7989.8 5247.6 5253.0 5258.7 5343.2 5400.6 8958.2 9282.2 9296.9 9306.7 9394.3 8390.0 9216.5 9379.1 9472.6 9550.7 6554.4 7116.2 7275.2 7328.7 7496.5 7897.4 8207.5 8279.7 8283.1 8320.4 7489.6 7517.2 7544.1 7578.3 7746.5 7250.1 7319.6 7320.5 7337.0 7340.8 6087.8 6093.5 6095.5 6152.7 6175.0 4781.7 4865.5 4908.7 4922.2 4994.1 4891.2 4943.3 5047.8 5066.7 5122.7 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | [#4] prototype.html Cycles:180 Average:4633.85 Median:5320.17 stddev:1330.88 (25.0%) stddev-sans-first:1295.80 05:35:44 INFO - PROCESS | 24989 | Values: 382.4 383.2 389.8 396.2 404.7 5267.0 5333.9 5338.8 5357.3 5384.2 5109.7 5246.2 5325.8 5340.2 5340.4 4145.1 4184.1 4206.3 4222.5 4242.5 4225.3 4230.0 4242.6 4243.1 4255.6 4120.9 4162.6 4186.8 4215.7 4221.7 4156.7 4177.4 4182.8 4219.3 4229.5 3753.9 3767.0 3772.5 3797.5 3801.2 5172.3 5189.3 5224.9 5241.7 5241.9 4481.5 4663.4 4674.3 4686.1 4698.6 5473.8 5548.0 5554.7 5556.6 5625.2 4022.6 4037.2 4051.1 4072.4 4080.9 5385.2 5547.0 5599.8 5707.6 5763.8 3985.2 4062.9 4065.2 4068.3 4097.5 4140.0 4142.8 4145.7 4150.4 4172.9 4537.5 4538.2 4639.5 4768.9 4783.3 5555.1 5567.7 5576.6 5587.3 5626.2 1368.3 1385.1 1387.0 1391.3 1397.5 897.5 961.6 968.9 972.3 979.8 5129.4 5151.5 5164.0 5167.3 5168.0 5402.6 5415.3 5420.3 5442.7 5456.8 5028.8 5056.8 5135.0 5173.4 5240.5 2859.8 2896.7 2899.1 2910.0 2915.7 5698.9 5705.2 5709.5 5721.3 5729.6 5395.9 5453.5 5554.6 5602.6 5649.9 5703.9 5717.7 5721.4 5731.4 5755.9 5307.0 5324.5 5449.7 5482.4 5496.3 5345.7 5369.7 5379.4 5439.5 5474.3 5561.3 5662.2 5674.7 5683.8 5724.7 4036.5 4055.6 4059.4 4062.1 4062.5 5632.3 5647.7 5689.8 5693.3 5738.8 5266.7 5523.9 5547.8 5572.1 5588.7 5507.5 5532.3 5537.7 5545.7 5550.3 5315.8 5569.5 5571.4 5576.5 5588.0 5544.6 5548.7 5565.7 5576.6 5597.7 5459.5 5461.9 5469.9 5473.8 5479.0 05:35:44 INFO - PROCESS | 24989 | 05:35:44 INFO - PROCESS | 24989 | [#5] yui.html Cycles:180 Average:16747.37 Median:28479.74 stddev:21318.04 (74.9%) stddev-sans-first:21343.79 05:35:44 INFO - PROCESS | 24989 | Values: 703.0 763.3 795.6 829.9 856.8 14106.7 14486.9 14513.3 14516.5 14544.3 14787.1 14850.3 14977.3 15037.8 15040.0 764.6 781.9 782.7 791.2 792.6 795.9 798.1 798.3 798.5 799.3 834.1 834.2 834.5 836.1 836.5 823.4 829.4 830.7 832.1 832.6 603.6 605.7 606.9 607.1 608.0 14444.7 14445.0 14520.4 14997.5 15510.0 14107.8 14639.1 14680.8 14780.0 14847.8 12042.6 12262.6 12264.9 12533.0 12543.7 3679.7 3718.1 3727.1 3773.4 3774.0 73339.3 73440.3 75548.0 76732.6 78250.2 10676.4 10679.4 10725.0 10790.0 10969.3 21525.4 21574.1 21770.0 21851.3 21998.6 7443.6 7478.7 7484.1 7495.1 7770.8 4062.8 4131.3 4193.8 4204.8 4211.9 3603.5 3653.7 3666.9 3686.1 3687.7 623.0 626.9 630.6 636.7 639.8 719.9 722.9 738.4 761.1 774.9 10391.3 10423.2 10429.7 10447.0 10576.4 679.5 680.2 684.5 695.1 695.6 4609.2 4995.7 5023.9 5032.3 5043.7 74873.6 75023.0 75869.5 75944.6 77196.0 64975.4 65711.3 65750.7 65898.3 65909.3 56708.1 56741.0 56885.4 57097.7 57530.4 9251.6 9736.6 9864.9 9878.5 9882.5 40463.8 41873.6 42103.4 42432.6 42971.8 49939.5 51915.7 52720.7 52780.0 53636.0 10223.3 10484.8 10575.6 10596.3 11090.1 12673.6 13163.9 13476.3 13830.4 14084.5 12002.3 12577.9 12712.3 13252.1 13425.4 11835.1 12118.4 12215.6 12375.5 12643.7 11570.2 12158.8 12385.4 12897.4 12907.4 9047.8 9450.8 9559.2 9566.8 9790.6 8889.8 9117.4 9630.8 9672.7 9673.7 05:35:44 INFO - PROCESS | 24989 | -------- Summary: end -------- 05:35:44 INFO - PROCESS | 24989 | 05:35:45 INFO - TEST-INFO | 24989: exit 0 05:35:45 INFO - TEST-OK | dromaeo_css | took 1117181ms 05:35:45 INFO - TEST-START | kraken 05:35:45 INFO - operating with platform_type : linux_ 05:35:45 INFO - Initialising browser for kraken test... 05:35:45 INFO - TEST-INFO | started process 25292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppdMGha/profile http://localhost:60691/getInfo.html) 05:35:48 INFO - PROCESS | 25292 | Iterating over profile /tmp/tmppdMGha/profile 05:35:49 INFO - PROCESS | 25292 | __metrics Screen width/height:1600/1200 05:35:49 INFO - PROCESS | 25292 | colorDepth:24 05:35:49 INFO - PROCESS | 25292 | Browser inner width/height: 1024/697 05:35:49 INFO - PROCESS | 25292 | __metrics 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/places.sqlite-wal = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/content-prefs.sqlite = Thu Feb 16 2017 05:35:47 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/mimeTypes.rdf = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/compatibility.ini = Thu Feb 16 2017 05:35:46 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/extensions = Thu Feb 16 2017 05:35:46 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/directoryLinks.json = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/bookmarkbackups = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/cache2 = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/localstore.rdf = Thu Feb 16 2017 05:16:25 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/webappsstore.sqlite = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/cookies.sqlite = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/places.sqlite = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/extensions.json = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/addons.json = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/safebrowsing = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/permissions.sqlite = Thu Feb 16 2017 05:35:46 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/key3.db = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/startupCache = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/pluginreg.dat = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | Stat failure 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/cert8.db = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/places.sqlite-shm = Thu Feb 16 2017 05:35:47 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/prefs.js = Thu Feb 16 2017 05:16:25 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/minidumps = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | Stat failure 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/.parentlock = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/extensions.ini = Thu Feb 16 2017 05:35:46 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/thumbnails = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/gmp = Thu Feb 16 2017 05:35:46 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/secmod.db = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/sessionCheckpoints.json = Thu Feb 16 2017 05:35:49 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/frequencyCap.json = Thu Feb 16 2017 05:35:48 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/crashes = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/user.js = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:49 INFO - PROCESS | 25292 | No birth date. Using mtime. 05:35:49 INFO - PROCESS | 25292 | Using date: /tmp/tmppdMGha/profile/blocklist.xml = Thu Feb 16 2017 05:35:45 GMT-0800 (PST) 05:35:50 INFO - PROCESS | 25292 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:35:50 INFO - PROCESS | 25292 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:35:51 INFO - TEST-INFO | 25292: exit 0 05:35:51 INFO - Browser initialized. 05:35:51 INFO - Running cycle 1/1 for kraken test... 05:35:51 INFO - TEST-INFO | started process 25379 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppdMGha/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:03 INFO - PROCESS | 25379 | RSS: Main: 149483520 05:36:03 INFO - PROCESS | 25379 | 05:36:06 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60691/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:07 INFO - PROCESS | 25379 | RSS: Main: 205709312 05:36:07 INFO - PROCESS | 25379 | 05:36:09 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60691/tests/kraken/driver.html?testName=audio-dft) 05:36:10 INFO - PROCESS | 25379 | RSS: Main: 185094144 05:36:10 INFO - PROCESS | 25379 | 05:36:13 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60691/tests/kraken/driver.html?testName=audio-fft) 05:36:13 INFO - PROCESS | 25379 | RSS: Main: 266670080 05:36:13 INFO - PROCESS | 25379 | 05:36:16 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60691/tests/kraken/driver.html?testName=audio-oscillator) 05:36:16 INFO - PROCESS | 25379 | RSS: Main: 178188288 05:36:16 INFO - PROCESS | 25379 | 05:36:18 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60691/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:19 INFO - PROCESS | 25379 | RSS: Main: 181723136 05:36:19 INFO - PROCESS | 25379 | 05:36:22 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60691/tests/kraken/driver.html?testName=imaging-darkroom) 05:36:22 INFO - PROCESS | 25379 | RSS: Main: 221208576 05:36:22 INFO - PROCESS | 25379 | 05:36:26 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60691/tests/kraken/driver.html?testName=imaging-desaturate) 05:36:26 INFO - PROCESS | 25379 | RSS: Main: 221253632 05:36:26 INFO - PROCESS | 25379 | 05:36:29 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60691/tests/kraken/driver.html?testName=json-parse-financial) 05:36:30 INFO - PROCESS | 25379 | RSS: Main: 219353088 05:36:30 INFO - PROCESS | 25379 | 05:36:31 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60691/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:32 INFO - PROCESS | 25379 | RSS: Main: 181567488 05:36:32 INFO - PROCESS | 25379 | 05:36:34 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:34 INFO - PROCESS | 25379 | RSS: Main: 189595648 05:36:34 INFO - PROCESS | 25379 | 05:36:36 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:36:37 INFO - PROCESS | 25379 | RSS: Main: 197615616 05:36:37 INFO - PROCESS | 25379 | 05:36:40 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:36:40 INFO - PROCESS | 25379 | RSS: Main: 207409152 05:36:40 INFO - PROCESS | 25379 | 05:36:43 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:36:44 INFO - PROCESS | 25379 | RSS: Main: 203726848 05:36:44 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | Cycle 1(1): loaded http://localhost:60691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60691/tests/kraken/driver.html?testName=ai-astar) 05:36:46 INFO - PROCESS | 25379 | RSS: Main: 255864832 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | __start_tp_report 05:36:46 INFO - PROCESS | 25379 | _x_x_mozilla_page_load 05:36:46 INFO - PROCESS | 25379 | _x_x_mozilla_page_load_details 05:36:46 INFO - PROCESS | 25379 | |i|pagename|runs| 05:36:46 INFO - PROCESS | 25379 | |0;ai-astar;113;116;113;115;115;115;112;113;114;113 05:36:46 INFO - PROCESS | 25379 | |1;audio-beat-detection;144;120;120;154;121;121;122;126;150;121 05:36:46 INFO - PROCESS | 25379 | |2;audio-dft;162;163;161;162;162;162;162;163;162;161 05:36:46 INFO - PROCESS | 25379 | |3;audio-fft;76;76;76;106;78;78;78;77;94;77 05:36:46 INFO - PROCESS | 25379 | |4;audio-oscillator;83;83;110;82;83;83;101;83;82;107 05:36:46 INFO - PROCESS | 25379 | |5;imaging-gaussian-blur;114;113;113;113;112;114;113;113;113;113 05:36:46 INFO - PROCESS | 25379 | |6;imaging-darkroom;147;148;147;147;147;147;147;146;147;146 05:36:46 INFO - PROCESS | 25379 | |7;imaging-desaturate;95;95;96;96;96;96;95;96;96;96 05:36:46 INFO - PROCESS | 25379 | |8;json-parse-financial;56;55;54;56;57;56;58;56;56;57 05:36:46 INFO - PROCESS | 25379 | |9;json-stringify-tinderbox;63;64;63;64;65;63;64;64;64;65 05:36:46 INFO - PROCESS | 25379 | |10;stanford-crypto-aes;85;86;84;84;85;88;94;84;84;82 05:36:46 INFO - PROCESS | 25379 | |11;stanford-crypto-ccm;132;132;136;131;129;128;140;135;172;154 05:36:46 INFO - PROCESS | 25379 | |12;stanford-crypto-pbkdf2;174;174;174;172;169;173;176;174;176;172 05:36:46 INFO - PROCESS | 25379 | |13;stanford-crypto-sha256-iterative;65;64;65;65;64;63;64;64;69;63 05:36:46 INFO - PROCESS | 25379 | __end_tp_report 05:36:46 INFO - PROCESS | 25379 | __start_cc_report 05:36:46 INFO - PROCESS | 25379 | _x_x_mozilla_cycle_collect,1679 05:36:46 INFO - PROCESS | 25379 | __end_cc_report 05:36:46 INFO - PROCESS | 25379 | __startTimestamp1487252206315__endTimestamp 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | ------- Summary: start ------- 05:36:46 INFO - PROCESS | 25379 | Number of tests: 14 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.50 stddev:1.29 (1.1%) stddev-sans-first:1.32 05:36:46 INFO - PROCESS | 25379 | Values: 113.0 116.0 113.0 115.0 115.0 115.0 112.0 113.0 114.0 113.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:124.00 stddev:13.72 (11.1%) stddev-sans-first:13.57 05:36:46 INFO - PROCESS | 25379 | Values: 144.0 120.0 120.0 154.0 121.0 121.0 122.0 126.0 150.0 121.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 05:36:46 INFO - PROCESS | 25379 | Values: 162.0 163.0 161.0 162.0 162.0 162.0 162.0 163.0 162.0 161.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#3] audio-fft Cycles:10 Average:81.60 Median:77.50 stddev:10.13 (13.1%) stddev-sans-first:10.54 05:36:46 INFO - PROCESS | 25379 | Values: 76.0 76.0 76.0 106.0 78.0 78.0 78.0 77.0 94.0 77.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:83.00 stddev:11.46 (13.8%) stddev-sans-first:11.90 05:36:46 INFO - PROCESS | 25379 | Values: 83.0 83.0 110.0 82.0 83.0 83.0 101.0 83.0 82.0 107.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 05:36:46 INFO - PROCESS | 25379 | Values: 114.0 113.0 113.0 113.0 112.0 114.0 113.0 113.0 113.0 113.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:36:46 INFO - PROCESS | 25379 | Values: 147.0 148.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 146.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 05:36:46 INFO - PROCESS | 25379 | Values: 95.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.00 stddev:1.10 (2.0%) stddev-sans-first:1.17 05:36:46 INFO - PROCESS | 25379 | Values: 56.0 55.0 54.0 56.0 57.0 56.0 58.0 56.0 56.0 57.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 05:36:46 INFO - PROCESS | 25379 | Values: 63.0 64.0 63.0 64.0 65.0 63.0 64.0 64.0 64.0 65.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:3.34 (3.9%) stddev-sans-first:3.54 05:36:46 INFO - PROCESS | 25379 | Values: 85.0 86.0 84.0 84.0 85.0 88.0 94.0 84.0 84.0 82.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:135.50 stddev:13.84 (10.2%) stddev-sans-first:14.45 05:36:46 INFO - PROCESS | 25379 | Values: 132.0 132.0 136.0 131.0 129.0 128.0 140.0 135.0 172.0 154.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:174.00 stddev:2.07 (1.2%) stddev-sans-first:2.18 05:36:46 INFO - PROCESS | 25379 | Values: 174.0 174.0 174.0 172.0 169.0 173.0 176.0 174.0 176.0 172.0 05:36:46 INFO - PROCESS | 25379 | 05:36:46 INFO - PROCESS | 25379 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:1.71 (2.7%) stddev-sans-first:1.81 05:36:46 INFO - PROCESS | 25379 | Values: 65.0 64.0 65.0 65.0 64.0 63.0 64.0 64.0 69.0 63.0 05:36:46 INFO - PROCESS | 25379 | -------- Summary: end -------- 05:36:46 INFO - PROCESS | 25379 | 05:36:47 INFO - TEST-INFO | 25379: exit 0 05:36:47 INFO - TEST-OK | kraken | took 61776ms 05:36:47 INFO - SUITE-END | took 1178s 05:36:47 INFO - Completed test suite (00:19:38) 05:36:47 INFO - javascript benchmark 05:36:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7047.882617900754, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3138.1526987713314, 3162.0988018414755, 3203.9839800800987, 3204.2149673330036, 3215.4694475411557, 5134.563562097217, 5194.324737784083, 5266.63133580649, 5379.704116273599, 5381.677099374036, 5286.550643195333, 5288.365396152458, 5313.335833020872, 5323.547498462625, 5392.622516423852, 4793.832215872445, 4845.733484658343, 4852.927206091911, 4865.148598995162, 4921.483244259355, 4946.084974279752, 4968.205087186051, 4994.250862370655, 5010.248462730601, 5015.724135172564, 4817.325574419584, 4865.6107511399005, 4866.051120031595, 4868.829590964335, 4874.098291816008, 4837.419509658828, 4895.902081958377, 4903.41159060915, 4986.326845875797, 4993.700377977333, 4875.398182443569, 4886.633502487292, 4902.975485122587, 4909.8772530686665, 4958.826441074545, 5389.703566303851, 5437.102878025121, 5438.592105592096, 5440.836774896759, 5450.018996580614, 5560.304961879765, 5623.690697011703, 5625.971870140623, 5693.28833895763, 5704.315482142128, 5611.410801865786, 5715.857103572403, 5728.25532680753, 5731.054376027951, 5733.140028995684, 5283.101872681654, 5305.363356397218, 5355.7857685692525, 5357.624966252364, 5359.490848369399, 5734.741936612862, 5784.681842498703, 5791.739371728282, 5796.855078623068, 5829.183914252008, 5536.889262214733, 5552.91670624941, 5560.471755183251, 5575.609707320448, 5583.944160558423, 5756.33802112754, 5757.510611598061, 5760.51035661965, 5763.682997435098, 5765.07758758605, 5690.231818704505, 5721.799737009184, 5770.538356931435, 5801.8549536261935, 5809.709514524341, 5802.100674395476, 5808.82573522795, 5824.213731146241, 5827.388124246978, 5829.591928565044, 4453.55464453552, 4462.598366146997, 4465.575770301816, 4469.709468884512, 4494.235979883428, 3961.782101984361, 3980.283548961215, 3985.043589538548, 4009.278329900588, 4021.416894550274, 5222.190560463135, 5260.789568417221, 5262.631615786935, 5321.840344789663, 5328.653637513549, 5714.628549144292, 5750.396208398141, 5791.218185544897, 5861.296644402613, 5873.295204575393, 5502.0, 5502.2296878437055, 5504.91742623861, 5533.612647114744, 5548.86127846799, 5009.674371165863, 5028.34631497903, 5033.068882256721, 5035.0, 5072.695638261643, 5802.709864506842, 5828.6211396259105, 5839.240898683144, 6079.996800527952, 6092.817215483543, 5628.634138780966, 5654.660720356792, 5665.461781130786, 5680.9774240635425, 5713.542916566747, 5878.2358293421585, 5923.970380148072, 5927.7036148191555, 5982.551308651956, 6004.669743164168, 5662.971685141548, 5665.66006039639, 5666.546676265972, 5666.603337766482, 5685.488306052475, 5609.6073274872, 5655.123455864347, 5660.009498337857, 5758.25142731459, 5768.134779783066, 5501.614886957875, 5516.613837031369, 5579.107342825128, 5586.636868603528, 5631.324241091095, 5611.663300202001, 5672.262605861211, 5673.0, 5673.8297851064535, 5714.857128571819, 5539.446055394428, 5595.720213989365, 5599.944000559943, 5603.495685388335, 5607.130894711327, 5460.672359658433, 5502.80740174092, 5588.133839254922, 5645.379008309164, 5659.9151012735665, 5294.841154765363, 5329.520343169133, 5333.813316533902, 5375.3280839895015, 5558.916616250679], "value": 5292.349158707952, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.9125991021111, 598.7036416973598, 604.5465900574569, 604.6674329118985, 605.4399680295726, 30481.39037219257, 31104.0, 33449.83275083621, 33458.33083338332, 33459.49810752841, 43313.13373732531, 44809.55190448095, 44858.32712509307, 49228.0, 49268.26097608539, 9258.67594634188, 9413.905860941371, 9416.858747118764, 9419.858702119474, 9423.34036617437, 7586.962065189667, 7770.4172187085915, 9302.790637217153, 9411.576479058425, 9422.858657120149, 10778.676639700783, 11024.393658348788, 11026.779464410707, 11442.199046066737, 11445.942770286138, 11149.21955463118, 11206.103511719084, 11220.495077721522, 11228.775424491547, 11236.66290011301, 10897.945510272399, 10977.890221097767, 10991.890081099167, 10994.28537145083, 10998.285111467729, 42636.14727705428, 42676.14647707059, 42798.57201428008, 42823.0, 42985.570144298785, 3722.646348596879, 3811.9809400953095, 3835.7506762060375, 3839.9808000960097, 3896.8051597420017, 26659.46681066387, 28640.283992900346, 28650.0, 28716.712832871614, 28828.567571486445, 4109.527404348508, 4164.729292595972, 4266.402703621496, 4451.5103338632725, 4466.017476155241, 239375.0, 240380.0, 241371.0, 242469.0, 242850.0, 49507.75246123746, 49683.25475117876, 49918.0, 50247.49752502501, 50260.74869625628, 48068.58799710037, 48396.27405588919, 48462.75768621134, 48470.757646212245, 48489.515104849204, 15402.84597154014, 15692.92153539225, 15787.526374208792, 15801.841981580266, 15803.051816891024, 11956.163068585116, 12117.87882121185, 12118.394080296126, 12124.696882578006, 12127.878721212852, 14985.925070374578, 15299.082055076731, 17033.659326813642, 17510.562235944202, 17546.210420531108, 6638.444198983426, 6808.38724514796, 6843.281455447206, 7026.664933662588, 7042.394314103712, 836.9372297077743, 860.1699360117517, 860.5396113079472, 877.4954401219325, 888.3958907942532, 28810.42379152447, 29625.85187074094, 30063.549046763877, 30413.0, 30450.390992180033, 1038.657243109772, 1043.5929987304958, 1056.0442799266675, 1063.5639387850945, 1064.5369264370024, 15334.23328833554, 16343.836561634469, 16409.507714768577, 16424.589385265226, 16561.83438165603, 470544.53836963343, 473751.0, 474516.0, 475412.0, 476355.0, 61434.38565614376, 61753.38246617566, 61830.381696183365, 62014.0, 62160.067598986054, 1303.2897071096274, 1362.8500864904852, 1369.0690330575305, 1375.5460697969845, 1379.8620137986322, 28945.0, 30258.84870575633, 30313.848430757706, 30832.383352333276, 30854.53718194274, 52393.7380313096, 52866.51903764516, 52913.0, 52971.73514132405, 53023.73488132535, 290575.0, 292627.0, 292798.0, 293230.0, 294912.0, 26018.0, 26081.217563473125, 26359.0, 27416.58875116835, 27742.306442338297, 35913.46129808003, 36119.81940090283, 44952.775236124915, 46177.76911115423, 46240.0, 35738.0, 37752.81123594364, 39095.02262443462, 39108.60891391164, 39801.60198397979, 37569.275160440215, 38666.42000369941, 39310.60689393069, 40192.59807401888, 40519.59480405158, 39077.8046109779, 40140.1971960565, 40147.397789032606, 40180.95476130967, 40476.59523404846, 33291.500627490124, 34137.658623413445, 34706.479402809484, 34859.82570087133, 34994.300113998084, 25990.610140847522, 26005.479890402465, 26299.21102366932, 26640.866795665897, 26937.567944562077], "value": 19070.98865678897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.66697474583475, 407.14499550943026, 424.4672935465991, 428.6506497204778, 444.67760873366814, 18700.90649546752, 19163.945982970912, 20183.798162018375, 20388.796112038875, 20589.27937522187, 20159.395218143443, 20322.390328290174, 20440.08019639116, 20475.488112797186, 20669.483262918435, 5713.114467257582, 5990.9101363479585, 6018.217631707894, 6018.277806663206, 6029.547783916202, 5803.7968671096305, 5891.587588868781, 6028.1861948636815, 6142.0, 6142.0786881967615, 5897.085951677497, 5910.615809972338, 5912.88174236515, 5913.615614985034, 5915.940840591604, 5910.1134829775665, 5919.556033297498, 5921.674307913063, 6152.907706384408, 6206.348333425016, 6135.6625385603775, 6154.230721159855, 6155.784547540859, 6158.753649854, 6163.445289923883, 22086.226982055712, 24038.75961240383, 24064.278071657878, 24128.0, 24248.393790155213, 2535.657686212356, 2650.3374156460886, 2652.350174207312, 2664.813463057587, 2704.323919020245, 10411.375317480899, 10459.895401046044, 10728.570857165781, 11035.062019728333, 11082.667519974413, 1891.6216756648726, 1940.388777535072, 1948.4349538633778, 1954.5211423201406, 1957.8727382720078, 83785.0, 84142.57928710378, 84639.15360846375, 85589.0, 86614.5669271656, 11141.832872506919, 11270.605528806616, 11602.883971160349, 11811.291322520676, 11900.523979040914, 16797.832021679627, 16900.32398704038, 16978.15109244533, 17067.146642667816, 17082.829171708123, 6703.363180497942, 6873.896891546531, 7608.125065617414, 8133.677112257088, 8232.34141268697, 7512.849743005109, 7524.811879703051, 7583.165851756303, 7784.84430311402, 7786.727464538714, 4897.7795999180125, 5006.123928312531, 5029.0, 5158.458361872024, 5207.765650545734, 1926.6032126708023, 2033.6136134134467, 2044.2231951858198, 2110.7889211079078, 2121.2787652198326, 904.6426661468814, 921.1985572551923, 921.8755468011733, 922.0226559846584, 929.5677509957902, 18775.436736897915, 20056.197752090044, 20235.595288094155, 20447.28434504785, 20459.897700511403, 914.4020798940313, 922.3681777982104, 945.2626950978248, 946.2997381937455, 951.9857202141974, 10258.538365773558, 10432.070067037623, 11177.776444471065, 11271.38007409584, 11300.095992320594, 529036.741509556, 531155.0, 531483.0, 536456.0, 542774.0, 99193.0, 99958.01973072645, 103037.0, 103618.75815457813, 104331.0, 1012.2104758288547, 1021.7803172317987, 1050.76883085721, 1053.031211285619, 1053.4574694032578, 16521.0, 16636.66726665484, 17174.0, 17518.38685645996, 17553.1223438825, 42679.57320426756, 43293.783531083405, 43364.34953475637, 43605.90985225268, 44478.332825007004, 57743.422565773806, 58314.0, 58376.416235837096, 58888.411115890005, 59375.40624593699, 6481.708323125565, 8624.18070283322, 8725.781855453664, 8759.343049271203, 9024.684136055206, 12673.429695663533, 13605.387757550736, 13765.449382024606, 13766.93116534411, 13817.378217980015, 6312.684365781784, 11287.7742445149, 11785.705357366134, 11848.466818992994, 12007.339596321937, 11710.297382157098, 11927.165098443025, 12132.393380330772, 12517.311547864954, 12524.49902003946, 11839.112066594867, 11875.82186267224, 11881.287122772315, 12049.33728644898, 12287.75424491523, 7450.292222238879, 7873.448858579844, 7972.92027079745, 8417.789555261168, 8715.607797649247, 8660.307175426076, 12000.819987700366, 12020.939895300467, 12070.396480176132, 12143.332116733665], "value": 9445.742615097619, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.9484420623176, 1428.0574820618388, 1430.5493769462619, 1430.7567713488713, 1436.5474875414247, 7538.811529711753, 8371.539565323905, 8531.914680853197, 8581.184787445198, 8787.340949428803, 8568.65725370986, 8666.73999780006, 8670.306375489961, 8696.21734043936, 8700.086490918457, 1882.4070417818411, 2225.4213904384897, 2246.539459410825, 2262.954740905181, 2264.852784569006, 1930.0446279091868, 2209.9226527071555, 2265.320403878838, 2272.522770218256, 2284.4403121235237, 2176.4341271269504, 2184.6722991551237, 2204.261572373257, 2205.911763529465, 2208.3595757230382, 2116.6719158530377, 2125.1818050050765, 2127.680847872816, 2131.925382611601, 2133.8826364549946, 2120.6606942889216, 2132.445564153316, 2208.447888027993, 2212.9889350553144, 2217.5121473275854, 8702.30381569473, 8725.563721813884, 8737.475751454933, 8739.169778870997, 8790.560471976376, 4994.275830004629, 5126.154184559543, 5126.538611524943, 5134.64057515974, 5155.587552995751, 3084.2443601317595, 3217.2278653123317, 3281.96718032819, 3285.4578994465883, 3302.3725492156655, 1298.8441387033522, 1321.8124004772117, 1353.1610401551102, 1358.7363751710905, 1402.7895815627635, 11290.887091129149, 11360.261582997162, 11372.48823802931, 11379.829302560469, 11394.202405831513, 4355.651547876226, 4503.234450143484, 4561.452625684939, 4617.307403889444, 4648.302754586772, 10236.53935572901, 10237.74405639865, 10240.692779216635, 10241.84637230442, 10256.333338332985, 5387.892242155213, 5391.32608423947, 5391.595630327741, 5393.164059570773, 5395.7841686332895, 4677.76611169447, 4759.666823322404, 4807.975960120177, 4839.491853355417, 4855.271709243641, 3820.8662696805477, 3879.165979314461, 3901.4147877817986, 3950.5654378018394, 4008.6191811777835, 1255.6923553729396, 1259.80473026681, 1263.5072321794507, 1287.2212311551564, 1292.4700872642172, 822.8066404394963, 828.4821986258588, 846.0363198415444, 866.9739907802776, 873.2708188662411, 5057.241413787961, 5205.869853253624, 5265.447128051576, 5304.575633949275, 5306.495882891119, 606.5541826757329, 623.4793947054261, 624.194788722552, 626.9028300613412, 627.1721327847198, 3268.9182770430452, 3314.784539005005, 3328.1679580104974, 3343.8160901150673, 3452.3095380923914, 9972.950135249423, 10378.948105259426, 10398.0, 10409.583616655249, 10420.843687344695, 10154.593816247267, 10308.0, 10337.0696637303, 10343.844842327371, 10349.172066234683, 7741.148473667892, 7879.448438609243, 7924.088729796032, 7946.7615971520945, 7989.840203195903, 5247.606429517725, 5252.9737351313, 5258.737063146751, 5343.198520221998, 5400.62195646301, 8958.193762561401, 9282.21101206405, 9296.907030929604, 9306.72079837606, 9394.342396032212, 8389.95805020971, 9216.539173041187, 9379.108984646447, 9472.621095156117, 9550.665726699532, 6554.377334153248, 7116.181639111362, 7275.236100209613, 7328.670209840677, 7496.475246732676, 7897.368210543028, 8207.548584827653, 8279.710210142615, 8283.08886022525, 8320.417570769989, 7489.550626962399, 7517.173110957681, 7544.094708634999, 7578.317951384402, 7746.457747957589, 7250.129984401906, 7319.634018298957, 7320.487565870338, 7337.046183996046, 7340.779776606711, 6087.847803804941, 6093.451589356801, 6095.481884039729, 6152.692365381624, 6175.0, 4781.665283430266, 4865.464798872192, 4908.680935739166, 4922.237053256751, 4994.1260279450225, 4891.192953162688, 4943.305898285515, 5047.823326183566, 5066.746662666926, 5122.743862806919], "value": 4366.579476253632, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.41872354021905, 383.18190662934643, 389.8460108257237, 396.24317553472866, 404.71063189819284, 5266.973665131673, 5333.866653333668, 5338.786448542059, 5357.30355053843, 5384.165454354572, 5109.718965456898, 5246.235729407809, 5325.7869685212545, 5340.198970154479, 5340.385855626604, 4145.129522800215, 4184.100418410041, 4206.263903816835, 4222.535521092677, 4242.533321334666, 4225.260579398593, 4229.978850105746, 4242.621148126856, 4243.090609225846, 4255.553166917475, 4120.890056524158, 4162.625363717265, 4186.832526698943, 4215.683823713219, 4221.662267018646, 4156.667466602695, 4177.394277829698, 4182.769947652878, 4219.312275089957, 4229.471316085489, 3753.9249215015548, 3766.9811650941842, 3772.5472943246714, 3797.5063241778666, 3801.2207497462955, 5172.250023746536, 5189.273501709764, 5224.92162617561, 5241.659292145999, 5241.86895327616, 4481.5070342262325, 4663.437748354306, 4674.345591617176, 4686.133065382915, 4698.624110071186, 5473.8357849264585, 5548.0, 5554.666719996772, 5556.611037227396, 5625.156226566007, 4022.612198791412, 4037.212743515016, 4051.1290072634524, 4072.3891416287497, 4080.9387859182134, 5385.246065550826, 5546.99999999996, 5599.804006859781, 5707.571932105108, 5763.827085187451, 3985.20295940813, 4062.857799976987, 4065.2276067547073, 4068.3287257602165, 4097.549269580344, 4139.97930010348, 4142.772147531915, 4145.66834653227, 4150.398192262165, 4172.937405938914, 4537.523560026149, 4538.205813982539, 4639.536046395334, 4768.856934291976, 4783.330333753277, 5555.138953462219, 5567.721613919288, 5576.633724737465, 5587.32952045749, 5626.156076588462, 1368.3158420789605, 1385.0512399006714, 1386.9791953120712, 1391.3460673483446, 1397.5178563395611, 897.4747524123213, 961.5672947173659, 968.9127978481971, 972.3339512434005, 979.7550612346913, 5129.410117836422, 5151.536361727388, 5164.0, 5167.276581278624, 5167.974160129175, 5402.621816472888, 5415.296011518478, 5420.34955805298, 5442.700651464128, 5456.836294911159, 5028.79884804611, 5056.82301119459, 5134.9690492962845, 5173.430922598511, 5240.4540660505245, 2859.7702987715484, 2896.7392934635564, 2899.144752298077, 2910.039686903317, 2915.6501219853753, 5698.886022279531, 5705.2012718219485, 5709.543236541067, 5721.313442386941, 5729.5702822288495, 5395.86863189046, 5453.509184173444, 5554.583581186772, 5602.627356297733, 5649.858753531194, 5703.8859222816145, 5717.685527295956, 5721.427857214246, 5731.426857314235, 5755.9424405756245, 5307.0, 5324.520793128637, 5449.7002664852225, 5482.39693633692, 5496.340439147168, 5345.717027913351, 5369.731513424391, 5379.381371142369, 5439.525016117736, 5474.315710536183, 5561.332640083216, 5662.150677398423, 5674.659520428788, 5683.801066962802, 5724.656520608777, 4036.535798383106, 4055.6349928506575, 4059.3505039193587, 4062.1469491405956, 4062.471878655756, 5632.295963004624, 5647.745851436777, 5689.805140920453, 5693.259876216065, 5738.770449181986, 5266.657667251617, 5523.917141242965, 5547.750351234124, 5572.108462645957, 5588.6527658382765, 5507.476789704971, 5532.28080349552, 5537.6709589698985, 5545.72271386421, 5550.306211723535, 5315.813946512008, 5569.498745112959, 5571.439996800902, 5576.470235327637, 5588.020817724995, 5544.63959842609, 5548.722563871871, 5565.666060036411, 5576.609637325349, 5597.720113994365, 5459.454054594509, 5461.918071228855, 5469.924905945097, 5473.8357849264585, 5479.0], "value": 4224.60879668258, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.964851757412, 763.3435245688703, 795.5783434779565, 829.8672212446008, 856.8200677857648, 14106.717865642693, 14486.927565362184, 14513.346899389526, 14516.491922782705, 14544.272786360667, 14787.112773233614, 14850.257487125627, 14977.251137443138, 15037.849621503781, 15040.0, 764.5603777827757, 781.9218078192192, 782.6517199846071, 791.2443616346376, 792.6195426195429, 795.9482633628825, 798.0702481608919, 798.2655956519986, 798.4889670610813, 799.312591171592, 834.1200033964195, 834.157500924068, 834.470111479209, 836.1011912194415, 836.506461187899, 823.423603477565, 829.4401279136558, 830.7424698343533, 832.0930186097183, 832.633641197871, 603.5594016368032, 605.7153138025121, 606.9484093852028, 607.068150389155, 608.0210860514568, 14444.711105777931, 14445.0, 14520.419183232658, 14997.475088371964, 15510.0, 14107.78838317426, 14639.121652700765, 14680.779788303184, 14780.0, 14847.777283340758, 12042.63872083839, 12262.646760400019, 12264.938675306656, 12533.0, 12543.686407839787, 3679.6688298053036, 3718.1262403335195, 3727.14275716584, 3773.3773927301963, 3773.962260377416, 73339.26660733431, 73440.26559734388, 75548.0, 76732.61633691852, 78250.21749782543, 10676.359418434764, 10679.412632305297, 10725.0, 10790.0, 10969.341839489496, 21525.354239372842, 21574.137034518757, 21770.0, 21851.344459666234, 21998.560028799333, 7443.627818609048, 7478.70085196586, 7484.1393239777035, 7495.138059123271, 7770.844583108306, 4062.8171732272117, 4131.29767939451, 4193.832246710097, 4204.768737719455, 4211.936820947688, 3603.477495763152, 3653.707703383723, 3666.8716594919583, 3686.09690625793, 3687.7418580699095, 622.9937700623026, 626.9028300613321, 630.5995692735146, 636.6689321552767, 639.8304449320933, 719.9388052015532, 722.8518153778463, 738.4350971506801, 761.105700801567, 774.8682723936944, 10391.272610917315, 10423.21825863048, 10429.68710938673, 10446.996563095308, 10576.365418074785, 679.5413096160072, 680.1701923653135, 684.4797953555334, 695.0720787748312, 695.5652717051843, 4609.216433206363, 4995.725235112031, 5023.899522009539, 5032.295478632995, 5043.747812609355, 74873.62563187149, 75023.0, 75869.51631046119, 75944.62027689937, 77196.0, 64975.350246497874, 65711.34288657052, 65750.67124664345, 65898.34101659017, 65909.34090659033, 56708.149377759364, 56741.0, 56885.43114568884, 57097.71451142718, 57530.42469575251, 9251.629934802533, 9736.561854716412, 9864.901350986398, 9878.4566848824, 9882.505874706092, 40463.797681011405, 41873.58126418697, 42103.36844947267, 42432.575674242864, 42971.78514107409, 49939.50060499348, 51915.740421297654, 52720.73639631777, 52780.0, 53636.0, 10223.28437009432, 10484.790304194026, 10575.62985295511, 10596.31123976939, 11090.112790976864, 12673.556425525063, 13163.881070109148, 13476.326183690971, 13830.37763300674, 14084.507042253472, 12002.339871307162, 12577.87422125767, 12712.3008234548, 13252.072354935062, 13425.395857186255, 11835.11236657237, 12118.394080296126, 12215.572454964034, 12375.504979800708, 12643.747125057263, 11570.19008669385, 12158.817617735564, 12385.442655080362, 12897.355132243163, 12907.354632268161, 9047.773805654911, 9450.763730906783, 9559.187469065208, 9566.808663826823, 9790.608375664893, 8889.777755556162, 9117.407368521024, 9630.807383852423, 9672.70981870545, 9673.661421850202], "value": 6968.200550044778, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 113.0, 115.0, 115.0, 115.0, 112.0, 113.0, 114.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 120.0, 120.0, 154.0, 121.0, 121.0, 122.0, 126.0, 150.0, 121.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 162.0, 162.0, 162.0, 162.0, 163.0, 162.0, 161.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 106.0, 78.0, 78.0, 78.0, 77.0, 94.0, 77.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 110.0, 82.0, 83.0, 83.0, 101.0, 83.0, 82.0, 107.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 56.0, 57.0, 56.0, 58.0, 56.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 64.0, 65.0, 63.0, 64.0, 64.0, 64.0, 65.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 84.0, 85.0, 88.0, 94.0, 84.0, 84.0, 82.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 136.0, 131.0, 129.0, 128.0, 140.0, 135.0, 172.0, 154.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 174.0, 172.0, 169.0, 173.0, 176.0, 174.0, 176.0, 172.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 64.0, 63.0, 64.0, 64.0, 69.0, 63.0], "value": 64.6, "unit": "score"}]}]} 05:36:47 INFO - Return code: 0 05:36:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:36:47 ERROR - # TBPL SUCCESS # 05:36:47 INFO - Running post-action listener: _resource_record_post_action 05:36:47 INFO - [mozharness: 2017-02-16 13:36:47.619312Z] Finished run-tests step (success) 05:36:47 INFO - Running post-run listener: _resource_record_post_run 05:36:47 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401375232; Read time: 16; Write time: 446976 05:36:47 INFO - TinderboxPrint: CPU usage
13.1% 05:36:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:36:47 INFO - TinderboxPrint: I/O write bytes / time
401,375,232 / 446,976 05:36:47 INFO - TinderboxPrint: CPU idle
8,298.1 (86.8%) 05:36:47 INFO - TinderboxPrint: CPU user
1,206.0 (12.6%) 05:36:47 INFO - TinderboxPrint: Swap in / out
0 / 0 05:36:47 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106098688; Read time: 0; Write time: 131596 05:36:47 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295063552; Read time: 16; Write time: 315356 05:36:48 INFO - Running post-run listener: _upload_blobber_files 05:36:48 INFO - Blob upload gear active. 05:36:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:36:48 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 05:36:48 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'] 05:36:48 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 05:36:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:36:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:36:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:36:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:49 INFO - (blobuploader) - INFO - Done attempting. 05:36:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:36:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:36:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:52 INFO - (blobuploader) - INFO - Done attempting. 05:36:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:36:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:53 INFO - (blobuploader) - INFO - Done attempting. 05:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:36:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:54 INFO - (blobuploader) - INFO - Done attempting. 05:36:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:36:54 INFO - Return code: 0 05:36:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:36:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:36:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9acfe1f74f4d225372c7cba31c7da194aca4211a289618a71717678ae873c3ef6cc9b6c24c11d5eba82ef8f440f60b5e6ddc9e44bb335af0be095438871c02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb9fd49e51e887dedbc7c8e74b0c3de18a3837cced5733b31c4536ec61936923863d9913303222ce7608548c2ecbbf1626e5c57459def4ae0646e5f1526b3bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c0a0fd11d919403f467ff13a1f75f90d73b8b9bfa63811c48c4d06ecc7a6d27ecf3f4ba1016fc1615543ad9b1fa97975f59f280885d61ecec3f650e930c9748", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b0b4a817e26e02f21584a2b54c8f00debb87360957d5dc63fd4c535683bfced41ad18f79e3e34dd351eac40579582357aec6a5906dd1bb06d56282db5eba408"} 05:36:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:36:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:36:54 INFO - Contents: 05:36:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9acfe1f74f4d225372c7cba31c7da194aca4211a289618a71717678ae873c3ef6cc9b6c24c11d5eba82ef8f440f60b5e6ddc9e44bb335af0be095438871c02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb9fd49e51e887dedbc7c8e74b0c3de18a3837cced5733b31c4536ec61936923863d9913303222ce7608548c2ecbbf1626e5c57459def4ae0646e5f1526b3bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c0a0fd11d919403f467ff13a1f75f90d73b8b9bfa63811c48c4d06ecc7a6d27ecf3f4ba1016fc1615543ad9b1fa97975f59f280885d61ecec3f650e930c9748", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b0b4a817e26e02f21584a2b54c8f00debb87360957d5dc63fd4c535683bfced41ad18f79e3e34dd351eac40579582357aec6a5906dd1bb06d56282db5eba408"} 05:36:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:36:54 INFO - Copying logs to upload dir... 05:36:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:36:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.037543 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-16 05:36:54.115261) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 05:36:54.118861) ========= 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=cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601 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/9acfe1f74f4d225372c7cba31c7da194aca4211a289618a71717678ae873c3ef6cc9b6c24c11d5eba82ef8f440f60b5e6ddc9e44bb335af0be095438871c02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb9fd49e51e887dedbc7c8e74b0c3de18a3837cced5733b31c4536ec61936923863d9913303222ce7608548c2ecbbf1626e5c57459def4ae0646e5f1526b3bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c0a0fd11d919403f467ff13a1f75f90d73b8b9bfa63811c48c4d06ecc7a6d27ecf3f4ba1016fc1615543ad9b1fa97975f59f280885d61ecec3f650e930c9748", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b0b4a817e26e02f21584a2b54c8f00debb87360957d5dc63fd4c535683bfced41ad18f79e3e34dd351eac40579582357aec6a5906dd1bb06d56282db5eba408"} build_url:https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/Acj_ciNEQV6TPMsd9BDr9A/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9acfe1f74f4d225372c7cba31c7da194aca4211a289618a71717678ae873c3ef6cc9b6c24c11d5eba82ef8f440f60b5e6ddc9e44bb335af0be095438871c02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb9fd49e51e887dedbc7c8e74b0c3de18a3837cced5733b31c4536ec61936923863d9913303222ce7608548c2ecbbf1626e5c57459def4ae0646e5f1526b3bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c0a0fd11d919403f467ff13a1f75f90d73b8b9bfa63811c48c4d06ecc7a6d27ecf3f4ba1016fc1615543ad9b1fa97975f59f280885d61ecec3f650e930c9748", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b0b4a817e26e02f21584a2b54c8f00debb87360957d5dc63fd4c535683bfced41ad18f79e3e34dd351eac40579582357aec6a5906dd1bb06d56282db5eba408"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 05:36:54.143368) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:36:54.143713) ========= 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=cbe409c6de289db6ac707de3000001d4-1487249844.87507-1473788601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:36:54.229317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 05:36:54.229652) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 05:36:54.229986) ========= ========= Total master_lag: 0.16 =========