builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1487364201.41 results: success (0) revision: 797fddcea4e02dab47921f2d2218a93e4a80d9cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.408202) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.408664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.408949) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.425094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.425416) ========= 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=31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 12:43:21-- 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% 49.8M=0s 2017-02-17 12:43:21 (49.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.195979 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.634584) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.634891) ========= 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=31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016286 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.687053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:21.687613) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 797fddcea4e02dab47921f2d2218a93e4a80d9cb --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 797fddcea4e02dab47921f2d2218a93e4a80d9cb --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=31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 12:43:22,054 truncating revision to first 12 chars 2017-02-17 12:43:22,054 Setting DEBUG logging. 2017-02-17 12:43:22,054 attempt 1/10 2017-02-17 12:43:22,054 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/797fddcea4e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 12:43:22,376 unpacking tar archive at: mozilla-central-797fddcea4e0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636987 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:22.340536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:22.341065) ========= script_repo_revision: 797fddcea4e02dab47921f2d2218a93e4a80d9cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:22.341695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:22.342166) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 12:43:22.354017) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-02-17 12:43:22.354643) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:43:22 INFO - MultiFileLogger online at 20170217 12:43:22 in /builds/slave/test 12:43:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:43:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:43:22 INFO - {'append_to_log': False, 12:43:22 INFO - 'base_work_dir': '/builds/slave/test', 12:43:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:43:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:43:22 INFO - 'branch': 'Firefox-Non-PGO', 12:43:22 INFO - 'buildbot_json_path': 'buildprops.json', 12:43:22 INFO - 'config_files': ('talos/linux_config.py',), 12:43:22 INFO - 'default_actions': ('clobber', 12:43:22 INFO - 'read-buildbot-config', 12:43:22 INFO - 'download-and-extract', 12:43:22 INFO - 'populate-webroot', 12:43:22 INFO - 'create-virtualenv', 12:43:22 INFO - 'install', 12:43:22 INFO - 'run-tests'), 12:43:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:43:22 INFO - 'download_minidump_stackwalk': True, 12:43:22 INFO - 'download_symbols': 'ondemand', 12:43:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:43:22 INFO - 'tooltool.py': '/tools/tooltool.py', 12:43:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:43:22 INFO - '/tools/misc-python/virtualenv.py')}, 12:43:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:43:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:43:22 INFO - 'installer_path': 'installer.exe', 12:43:22 INFO - 'log_level': 'info', 12:43:22 INFO - 'log_name': 'talos', 12:43:22 INFO - 'log_to_console': True, 12:43:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:43:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:43:22 INFO - 'opt_config_files': (), 12:43:22 INFO - 'pip_index': False, 12:43:22 INFO - 'sps_profile': False, 12:43:22 INFO - 'sps_profile_interval': 0, 12:43:22 INFO - 'suite': 'dromaeojs', 12:43:22 INFO - 'system_bits': '32', 12:43:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:43:22 INFO - 'title': 'talos-linux64-ix-014', 12:43:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:43:22 INFO - 'use_talos_json': True, 12:43:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:43:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:43:22 INFO - 'work_dir': 'build'} 12:43:22 INFO - [mozharness: 2017-02-17 20:43:22.816469Z] Running clobber step. 12:43:22 INFO - Running pre-action listener: _resource_record_pre_action 12:43:22 INFO - Running main action method: clobber 12:43:22 INFO - rmtree: /builds/slave/test/build 12:43:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:43:23 INFO - Running post-action listener: _resource_record_post_action 12:43:23 INFO - [mozharness: 2017-02-17 20:43:23.730701Z] Finished clobber step (success) 12:43:23 INFO - [mozharness: 2017-02-17 20:43:23.730802Z] Running read-buildbot-config step. 12:43:23 INFO - Running pre-action listener: _resource_record_pre_action 12:43:23 INFO - Running main action method: read_buildbot_config 12:43:23 INFO - Using buildbot properties: 12:43:23 INFO - { 12:43:23 INFO - "project": "", 12:43:23 INFO - "product": "firefox", 12:43:23 INFO - "who": "kwierso@gmail.com", 12:43:23 INFO - "installer_path": "public/build/target.tar.bz2", 12:43:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:43:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:43:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 12:43:23 INFO - "stage_platform": "linux64", 12:43:23 INFO - "basedir": "/builds/slave/test", 12:43:23 INFO - "buildnumber": 985, 12:43:23 INFO - "platform": "ubuntu64_hw", 12:43:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:43:23 INFO - "slavebuilddir": "test", 12:43:23 INFO - "taskId": "a7cIdugwRiaKEQGU4U7wOw", 12:43:23 INFO - "branch": "mozilla-central", 12:43:23 INFO - "script_repo_revision": "production", 12:43:23 INFO - "revision": "797fddcea4e02dab47921f2d2218a93e4a80d9cb", 12:43:23 INFO - "slavename": "talos-linux64-ix-014", 12:43:23 INFO - "repo_path": "mozilla-central" 12:43:23 INFO - } 12:43:23 INFO - Finding installer, test and symbols from parent task. 12:43:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a7cIdugwRiaKEQGU4U7wOw'}, attempt #1 12:43:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a7cIdugwRiaKEQGU4U7wOw'}, attempt #1 12:43:24 INFO - Task dependencies: U53J_7G2RWCH-wSTVWq2Qg 12:43:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg'}, attempt #1 12:43:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2 12:43:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/test_packages.json 12:43:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.crashreporter-symbols.zip 12:43:25 INFO - Running post-action listener: _resource_record_post_action 12:43:25 INFO - [mozharness: 2017-02-17 20:43:25.143658Z] Finished read-buildbot-config step (success) 12:43:25 INFO - [mozharness: 2017-02-17 20:43:25.143875Z] Running download-and-extract step. 12:43:25 INFO - Running pre-action listener: _resource_record_pre_action 12:43:25 INFO - Running main action method: download_and_extract 12:43:25 INFO - mkdir: /builds/slave/test/build/tests 12:43: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')]} 12:43:25 INFO - trying https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.test_packages.json 12:43:25 INFO - Downloading https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:43:25 INFO - Downloaded 896 bytes. 12:43:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:43:25 INFO - Using the following test package requirements: 12:43:25 INFO - {u'common': [u'target.common.tests.zip'], 12:43:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:43:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:43:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:43:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:43:25 INFO - u'mozbase': [u'target.common.tests.zip'], 12:43:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:43:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:43:25 INFO - u'web-platform': [u'target.common.tests.zip', 12:43:25 INFO - u'target.web-platform.tests.zip'], 12:43:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:43:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:43:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.common.tests.zip 12:43:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:43:25 INFO - Fetch https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.common.tests.zip into memory 12:43:28 INFO - Expected file size: 57027585 12:43:28 INFO - Obtained file size: 57027585 12:43:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:43:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.common.tests.zip 12:43:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:43:37 INFO - Fetch https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.common.tests.zip into memory 12:43:38 INFO - Expected file size: 57027585 12:43:38 INFO - Obtained file size: 57027585 12:43:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.talos.tests.zip 12:43:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:43:49 INFO - Fetch https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.talos.tests.zip into memory 12:43:51 INFO - Expected file size: 14403647 12:43:51 INFO - Obtained file size: 14403647 12:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:51 INFO - trying https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2 12:43:51 INFO - Downloading https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:43:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:43:53 INFO - Downloaded 61353878 bytes. 12:43:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2 12:43:53 INFO - mkdir: /builds/slave/test/properties 12:43:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:43:53 INFO - Writing to file /builds/slave/test/properties/build_url 12:43:53 INFO - Contents: 12:43:53 INFO - build_url:https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2 12:43:53 INFO - Running post-action listener: _resource_record_post_action 12:43:53 INFO - Running post-action listener: set_extra_try_arguments 12:43:53 INFO - [mozharness: 2017-02-17 20:43:53.272672Z] Finished download-and-extract step (success) 12:43:53 INFO - [mozharness: 2017-02-17 20:43:53.272793Z] Running populate-webroot step. 12:43:53 INFO - Running pre-action listener: _resource_record_pre_action 12:43:53 INFO - Running main action method: populate_webroot 12:43:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:43:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:43:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:43:53 INFO - '--tppagecycles', 12:43:53 INFO - '7'], 12:43:53 INFO - 'tests': ['tsvgm']}}, 12:43:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:43:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:43:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:43:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:43:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['damp', 'tps']}, 12:43:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'tests': ['damp', 'tps']}, 12:43:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['dromaeo_dom']}, 12:43:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:43:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['basic_compositor_video']}, 12:43:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:43:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['a11yr', 12:43:53 INFO - 'ts_paint', 12:43:53 INFO - 'tpaint', 12:43:53 INFO - 'sessionrestore', 12:43:53 INFO - 'sessionrestore_no_auto_restore', 12:43:53 INFO - 'tabpaint']}, 12:43:53 INFO - 'other-e10s': {'tests': ['a11yr', 12:43:53 INFO - 'ts_paint', 12:43:53 INFO - 'tpaint', 12:43:53 INFO - 'sessionrestore', 12:43:53 INFO - 'sessionrestore_no_auto_restore', 12:43:53 INFO - 'tabpaint']}, 12:43:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:43:53 INFO - 'ts_paint', 12:43:53 INFO - 'tpaint', 12:43:53 INFO - 'sessionrestore', 12:43:53 INFO - 'sessionrestore_no_auto_restore', 12:43:53 INFO - 'tabpaint']}, 12:43:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:43:53 INFO - 'ts_paint', 12:43:53 INFO - 'tpaint', 12:43:53 INFO - 'sessionrestore', 12:43:53 INFO - 'sessionrestore_no_auto_restore', 12:43:53 INFO - 'tabpaint']}, 12:43:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['a11yr', 12:43:53 INFO - 'ts_paint', 12:43:53 INFO - 'tpaint', 12:43:53 INFO - 'sessionrestore', 12:43:53 INFO - 'sessionrestore_no_auto_restore', 12:43:53 INFO - 'tabpaint']}, 12:43:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['a11yr', 12:43:53 INFO - 'ts_paint', 12:43:53 INFO - 'tpaint', 12:43:53 INFO - 'sessionrestore', 12:43:53 INFO - 'sessionrestore_no_auto_restore', 12:43:53 INFO - 'tabpaint']}, 12:43:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['tsvgx', 12:43:53 INFO - 'tsvgr_opacity', 12:43:53 INFO - 'tart', 12:43:53 INFO - 'tscrollx', 12:43:53 INFO - 'cart', 12:43:53 INFO - 'tsvg_static']}, 12:43:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:43:53 INFO - 'tsvgr_opacity', 12:43:53 INFO - 'tart', 12:43:53 INFO - 'tscrollx', 12:43:53 INFO - 'cart', 12:43:53 INFO - 'tsvg_static']}, 12:43:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'talos_options': ['--disable-e10s'], 12:43:53 INFO - 'tests': ['tp5o']}, 12:43:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:43:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'talos_options': ['--disable-e10s', 12:43:53 INFO - '--xperf_path', 12:43:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:53 INFO - 'tests': ['tp5n']}, 12:43:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:43:53 INFO - 'talos_options': ['--xperf_path', 12:43:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:53 INFO - 'tests': ['tp5n']}}, 12:43:53 INFO - 'talos.zip': {'path': '', 12:43:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:43:53 INFO - Running post-action listener: _resource_record_post_action 12:43:53 INFO - [mozharness: 2017-02-17 20:43:53.287698Z] Finished populate-webroot step (success) 12:43:53 INFO - [mozharness: 2017-02-17 20:43:53.287790Z] Running create-virtualenv step. 12:43:53 INFO - Running pre-action listener: _resource_record_pre_action 12:43:53 INFO - Running main action method: create_virtualenv 12:43:53 INFO - Creating virtualenv /builds/slave/test/build/venv 12:43:53 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 12:43:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:43:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:43:53 INFO - Using real prefix '/usr' 12:43:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:43:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:43:55 INFO - Installing pip.................done. 12:43:55 INFO - Return code: 0 12:43:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:43:56 INFO - Reading from file tmpfile_stdout 12:43:56 INFO - Output received: 12:43:56 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) 12:43:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:56 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:43:56 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 12:43:56 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 12:43:56 INFO - Using env: {'DISPLAY': ':0', 12:43:56 INFO - 'HOME': '/home/cltbld', 12:43:56 INFO - 'LANG': 'en_US.UTF-8', 12:43:56 INFO - 'LANGUAGE': 'en_US:en', 12:43:56 INFO - 'LOGNAME': 'cltbld', 12:43:56 INFO - 'MAIL': '/var/mail/cltbld', 12:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:56 INFO - 'MOZ_NO_REMOTE': '1', 12:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:56 INFO - 'NO_EM_RESTART': '1', 12:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:56 INFO - 'PWD': '/builds/slave/test', 12:43:56 INFO - 'SHELL': '/bin/bash', 12:43:56 INFO - 'SHLVL': '1', 12:43:56 INFO - 'TERM': 'linux', 12:43:56 INFO - 'TMOUT': '86400', 12:43:56 INFO - 'USER': 'cltbld', 12:43:56 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:56 INFO - '_': '/tools/buildbot/bin/python'} 12:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:56 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 12:43:56 INFO - Cleaning up... 12:43:56 INFO - Return code: 0 12:43:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:56 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:43:56 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 12:43:56 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 12:43:56 INFO - Using env: {'DISPLAY': ':0', 12:43:56 INFO - 'HOME': '/home/cltbld', 12:43:56 INFO - 'LANG': 'en_US.UTF-8', 12:43:56 INFO - 'LANGUAGE': 'en_US:en', 12:43:56 INFO - 'LOGNAME': 'cltbld', 12:43:56 INFO - 'MAIL': '/var/mail/cltbld', 12:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:56 INFO - 'MOZ_NO_REMOTE': '1', 12:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:56 INFO - 'NO_EM_RESTART': '1', 12:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:56 INFO - 'PWD': '/builds/slave/test', 12:43:56 INFO - 'SHELL': '/bin/bash', 12:43:56 INFO - 'SHLVL': '1', 12:43:56 INFO - 'TERM': 'linux', 12:43:56 INFO - 'TMOUT': '86400', 12:43:56 INFO - 'USER': 'cltbld', 12:43:56 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:56 INFO - '_': '/tools/buildbot/bin/python'} 12:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:56 INFO - Downloading/unpacking psutil>=3.1.1 12:43:56 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 12:43:56 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 12:43:56 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 12:43:56 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 12:43:56 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 12:43:56 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 12:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:43:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:58 INFO - Installing collected packages: psutil 12:43:58 INFO - Running setup.py install for psutil 12:43:58 INFO - building 'psutil._psutil_linux' extension 12:43:58 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 12:43:58 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 12:43:58 INFO - building 'psutil._psutil_posix' extension 12:43:58 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 12:43:59 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 12:43:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:59 INFO - Successfully installed psutil 12:43:59 INFO - Cleaning up... 12:43:59 INFO - Return code: 0 12:43:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:59 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:43:59 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 12:43:59 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 12:43:59 INFO - Using env: {'DISPLAY': ':0', 12:43:59 INFO - 'HOME': '/home/cltbld', 12:43:59 INFO - 'LANG': 'en_US.UTF-8', 12:43:59 INFO - 'LANGUAGE': 'en_US:en', 12:43:59 INFO - 'LOGNAME': 'cltbld', 12:43:59 INFO - 'MAIL': '/var/mail/cltbld', 12:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:59 INFO - 'MOZ_NO_REMOTE': '1', 12:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:59 INFO - 'NO_EM_RESTART': '1', 12:43:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:59 INFO - 'PWD': '/builds/slave/test', 12:43:59 INFO - 'SHELL': '/bin/bash', 12:43:59 INFO - 'SHLVL': '1', 12:43:59 INFO - 'TERM': 'linux', 12:43:59 INFO - 'TMOUT': '86400', 12:43:59 INFO - 'USER': 'cltbld', 12:43:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:59 INFO - '_': '/tools/buildbot/bin/python'} 12:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:43:59 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 12:43:59 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 12:43:59 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 12:43:59 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 12:43:59 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 12:43:59 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 12:44:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:44:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:44:01 INFO - Installing collected packages: mozsystemmonitor 12:44:01 INFO - Running setup.py install for mozsystemmonitor 12:44:01 INFO - Successfully installed mozsystemmonitor 12:44:01 INFO - Cleaning up... 12:44:01 INFO - Return code: 0 12:44:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:44: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')]} 12:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44: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')]} 12:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:01 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:01 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 12:44:01 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 12:44:01 INFO - Using env: {'DISPLAY': ':0', 12:44:01 INFO - 'HOME': '/home/cltbld', 12:44:01 INFO - 'LANG': 'en_US.UTF-8', 12:44:01 INFO - 'LANGUAGE': 'en_US:en', 12:44:01 INFO - 'LOGNAME': 'cltbld', 12:44:01 INFO - 'MAIL': '/var/mail/cltbld', 12:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:01 INFO - 'MOZ_NO_REMOTE': '1', 12:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:01 INFO - 'NO_EM_RESTART': '1', 12:44:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:01 INFO - 'PWD': '/builds/slave/test', 12:44:01 INFO - 'SHELL': '/bin/bash', 12:44:01 INFO - 'SHLVL': '1', 12:44:01 INFO - 'TERM': 'linux', 12:44:01 INFO - 'TMOUT': '86400', 12:44:01 INFO - 'USER': 'cltbld', 12:44:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:01 INFO - '_': '/tools/buildbot/bin/python'} 12:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:01 INFO - Downloading/unpacking jsonschema==2.5.1 12:44:01 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 12:44:01 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 12:44:01 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 12:44:01 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 12:44:01 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 12:44:01 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 12:44:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:44:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:44:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:04 INFO - Downloading functools32-3.2.3-2.tar.gz 12:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:44:04 INFO - warning: no files found matching '*.txt' 12:44:04 INFO - no previously-included directories found matching 'build' 12:44:04 INFO - no previously-included directories found matching 'dist' 12:44:04 INFO - no previously-included directories found matching '.git*' 12:44:04 INFO - Installing collected packages: jsonschema, functools32 12:44:04 INFO - Running setup.py install for functools32 12:44:04 INFO - warning: no files found matching '*.txt' 12:44:04 INFO - no previously-included directories found matching 'build' 12:44:04 INFO - no previously-included directories found matching 'dist' 12:44:04 INFO - no previously-included directories found matching '.git*' 12:44:04 INFO - Successfully installed jsonschema functools32 12:44:04 INFO - Cleaning up... 12:44:04 INFO - Return code: 0 12:44:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:44:04 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')]} 12:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:04 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')]} 12:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:04 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:04 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 12:44:04 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 12:44:04 INFO - Using env: {'DISPLAY': ':0', 12:44:04 INFO - 'HOME': '/home/cltbld', 12:44:04 INFO - 'LANG': 'en_US.UTF-8', 12:44:04 INFO - 'LANGUAGE': 'en_US:en', 12:44:04 INFO - 'LOGNAME': 'cltbld', 12:44:04 INFO - 'MAIL': '/var/mail/cltbld', 12:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:04 INFO - 'MOZ_NO_REMOTE': '1', 12:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:04 INFO - 'NO_EM_RESTART': '1', 12:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:04 INFO - 'PWD': '/builds/slave/test', 12:44:04 INFO - 'SHELL': '/bin/bash', 12:44:04 INFO - 'SHLVL': '1', 12:44:04 INFO - 'TERM': 'linux', 12:44:04 INFO - 'TMOUT': '86400', 12:44:04 INFO - 'USER': 'cltbld', 12:44:04 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:04 INFO - '_': '/tools/buildbot/bin/python'} 12:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:44:04 INFO - Cleaning up... 12:44:04 INFO - Return code: 0 12:44:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:44:04 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')]} 12:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:04 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')]} 12:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:04 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:04 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 12:44:04 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 12:44:04 INFO - Using env: {'DISPLAY': ':0', 12:44:04 INFO - 'HOME': '/home/cltbld', 12:44:04 INFO - 'LANG': 'en_US.UTF-8', 12:44:04 INFO - 'LANGUAGE': 'en_US:en', 12:44:04 INFO - 'LOGNAME': 'cltbld', 12:44:04 INFO - 'MAIL': '/var/mail/cltbld', 12:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:04 INFO - 'MOZ_NO_REMOTE': '1', 12:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:04 INFO - 'NO_EM_RESTART': '1', 12:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:04 INFO - 'PWD': '/builds/slave/test', 12:44:04 INFO - 'SHELL': '/bin/bash', 12:44:04 INFO - 'SHLVL': '1', 12:44:04 INFO - 'TERM': 'linux', 12:44:04 INFO - 'TMOUT': '86400', 12:44:04 INFO - 'USER': 'cltbld', 12:44:04 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:04 INFO - '_': '/tools/buildbot/bin/python'} 12:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:44:04 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 12:44:04 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 12:44:04 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 12:44:04 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 12:44:04 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 12:44:04 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 12:44:06 INFO - Downloading blobuploader-1.2.4.tar.gz 12:44:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:44:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:44:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:44:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:44:07 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 12:44:07 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 12:44:07 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 12:44:07 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 12:44:07 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 12:44:07 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 12:44:07 INFO - Downloading docopt-0.6.1.tar.gz 12:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:44:07 INFO - Installing collected packages: blobuploader, requests, docopt 12:44:07 INFO - Running setup.py install for blobuploader 12:44:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:44:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:44:07 INFO - Running setup.py install for requests 12:44:08 INFO - Running setup.py install for docopt 12:44:08 INFO - Successfully installed blobuploader requests docopt 12:44:08 INFO - Cleaning up... 12:44:08 INFO - Return code: 0 12:44:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44: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')]} 12:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44: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')]} 12:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:08 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:08 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 12:44:08 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 12:44:08 INFO - Using env: {'DISPLAY': ':0', 12:44:08 INFO - 'HOME': '/home/cltbld', 12:44:08 INFO - 'LANG': 'en_US.UTF-8', 12:44:08 INFO - 'LANGUAGE': 'en_US:en', 12:44:08 INFO - 'LOGNAME': 'cltbld', 12:44:08 INFO - 'MAIL': '/var/mail/cltbld', 12:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:08 INFO - 'MOZ_NO_REMOTE': '1', 12:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:08 INFO - 'NO_EM_RESTART': '1', 12:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:08 INFO - 'PWD': '/builds/slave/test', 12:44:08 INFO - 'SHELL': '/bin/bash', 12:44:08 INFO - 'SHLVL': '1', 12:44:08 INFO - 'TERM': 'linux', 12:44:08 INFO - 'TMOUT': '86400', 12:44:08 INFO - 'USER': 'cltbld', 12:44:08 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:08 INFO - '_': '/tools/buildbot/bin/python'} 12:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:08 INFO - Running setup.py (path:/tmp/pip-Zq7vr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:08 INFO - Running setup.py (path:/tmp/pip-cNp1be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:08 INFO - Running setup.py (path:/tmp/pip-TT2tSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:08 INFO - Running setup.py (path:/tmp/pip-Ygyaw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:08 INFO - Running setup.py (path:/tmp/pip-VMC9oX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:08 INFO - Running setup.py (path:/tmp/pip-99RdeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:08 INFO - Running setup.py (path:/tmp/pip-uGqvpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:08 INFO - Running setup.py (path:/tmp/pip-sWnJvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:09 INFO - Running setup.py (path:/tmp/pip-x7oY9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:09 INFO - Running setup.py (path:/tmp/pip-U2e_Y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:09 INFO - Running setup.py (path:/tmp/pip-B1f0EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:09 INFO - Running setup.py (path:/tmp/pip-aFK1jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:09 INFO - Running setup.py (path:/tmp/pip-eAGg1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:09 INFO - Running setup.py (path:/tmp/pip-1ycL37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:09 INFO - Running setup.py (path:/tmp/pip-GUeeKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:09 INFO - Running setup.py (path:/tmp/pip-Yyma2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:09 INFO - Running setup.py (path:/tmp/pip-a87APz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:44:09 INFO - Running setup.py install for manifestparser 12:44:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:44:09 INFO - Running setup.py install for mozcrash 12:44:09 INFO - Running setup.py install for mozdebug 12:44:10 INFO - Running setup.py install for mozdevice 12:44:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Running setup.py install for mozfile 12:44:10 INFO - Running setup.py install for mozhttpd 12:44:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Running setup.py install for mozinfo 12:44:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Running setup.py install for mozInstall 12:44:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Running setup.py install for mozleak 12:44:10 INFO - Running setup.py install for mozlog 12:44:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Running setup.py install for moznetwork 12:44:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:44:10 INFO - Running setup.py install for mozprocess 12:44:10 INFO - Running setup.py install for mozprofile 12:44:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:44:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:44:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:44:11 INFO - Running setup.py install for mozrunner 12:44:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:44:11 INFO - Running setup.py install for mozscreenshot 12:44:11 INFO - Running setup.py install for moztest 12:44:11 INFO - Running setup.py install for mozversion 12:44:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:44:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:44:11 INFO - Cleaning up... 12:44:11 INFO - Return code: 0 12:44:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:11 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:11 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 12:44:11 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 12:44:11 INFO - Using env: {'DISPLAY': ':0', 12:44:11 INFO - 'HOME': '/home/cltbld', 12:44:11 INFO - 'LANG': 'en_US.UTF-8', 12:44:11 INFO - 'LANGUAGE': 'en_US:en', 12:44:11 INFO - 'LOGNAME': 'cltbld', 12:44:11 INFO - 'MAIL': '/var/mail/cltbld', 12:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:11 INFO - 'MOZ_NO_REMOTE': '1', 12:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:11 INFO - 'NO_EM_RESTART': '1', 12:44:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:11 INFO - 'PWD': '/builds/slave/test', 12:44:11 INFO - 'SHELL': '/bin/bash', 12:44:11 INFO - 'SHLVL': '1', 12:44:11 INFO - 'TERM': 'linux', 12:44:11 INFO - 'TMOUT': '86400', 12:44:11 INFO - 'USER': 'cltbld', 12:44:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:11 INFO - '_': '/tools/buildbot/bin/python'} 12:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:11 INFO - Running setup.py (path:/tmp/pip-dPjFU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:11 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)) 12:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:11 INFO - Running setup.py (path:/tmp/pip-DSi8m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:11 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)) 12:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:11 INFO - Running setup.py (path:/tmp/pip-TrrrJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:11 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)) 12:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:11 INFO - Running setup.py (path:/tmp/pip-_kGBvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:12 INFO - Running setup.py (path:/tmp/pip-deOWIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:12 INFO - Running setup.py (path:/tmp/pip-tInsJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:12 INFO - Running setup.py (path:/tmp/pip-Qxdfu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:12 INFO - Running setup.py (path:/tmp/pip-9rBzaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:12 INFO - Running setup.py (path:/tmp/pip-OtYVvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:12 INFO - Running setup.py (path:/tmp/pip-xYM1gO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:12 INFO - Running setup.py (path:/tmp/pip-x1eEQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:12 INFO - Running setup.py (path:/tmp/pip-ZxzjRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:12 INFO - Running setup.py (path:/tmp/pip-eQ0mA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:12 INFO - Running setup.py (path:/tmp/pip-vJ_enl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:12 INFO - Running setup.py (path:/tmp/pip-kz4vCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:12 INFO - Running setup.py (path:/tmp/pip-9vaVkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:12 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)) 12:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:12 INFO - Running setup.py (path:/tmp/pip-L6TMWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:12 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)) 12:44:12 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)) 12:44:12 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)) 12:44:12 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)) 12:44:12 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)) 12:44:12 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)) 12:44:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:44:12 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 12:44:12 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 12:44:12 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 12:44:12 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 12:44:12 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 12:44:12 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 12:44:14 INFO - Downloading blessings-1.6.tar.gz 12:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:44:15 INFO - Installing collected packages: blessings 12:44:15 INFO - Running setup.py install for blessings 12:44:15 INFO - Successfully installed blessings 12:44:15 INFO - Cleaning up... 12:44:15 INFO - Return code: 0 12:44:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:15 INFO - Reading from file tmpfile_stdout 12:44:15 INFO - Current package versions: 12:44:15 INFO - argparse == 1.2.1 12:44:15 INFO - blessings == 1.6 12:44:15 INFO - blobuploader == 1.2.4 12:44:15 INFO - docopt == 0.6.1 12:44:15 INFO - functools32 == 3.2.3-2 12:44:15 INFO - jsonschema == 2.5.1 12:44:15 INFO - manifestparser == 1.1 12:44:15 INFO - mozInstall == 1.12 12:44:15 INFO - mozcrash == 1.0 12:44:15 INFO - mozdebug == 0.1 12:44:15 INFO - mozdevice == 0.49 12:44:15 INFO - mozfile == 1.2 12:44:15 INFO - mozhttpd == 0.7 12:44:15 INFO - mozinfo == 0.9 12:44:15 INFO - mozleak == 0.1 12:44:15 INFO - mozlog == 3.4 12:44:15 INFO - moznetwork == 0.27 12:44:15 INFO - mozprocess == 0.24 12:44:15 INFO - mozprofile == 0.28 12:44:15 INFO - mozrunner == 6.13 12:44:15 INFO - mozscreenshot == 0.1 12:44:15 INFO - mozsystemmonitor == 0.3 12:44:15 INFO - moztest == 0.8 12:44:15 INFO - mozversion == 1.4 12:44:15 INFO - psutil == 3.1.1 12:44:15 INFO - requests == 1.2.3 12:44:15 INFO - wsgiref == 0.1.2 12:44:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:15 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')]} 12:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:15 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')]} 12:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:15 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:15 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 12:44:15 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 12:44:15 INFO - Using env: {'DISPLAY': ':0', 12:44:15 INFO - 'HOME': '/home/cltbld', 12:44:15 INFO - 'LANG': 'en_US.UTF-8', 12:44:15 INFO - 'LANGUAGE': 'en_US:en', 12:44:15 INFO - 'LOGNAME': 'cltbld', 12:44:15 INFO - 'MAIL': '/var/mail/cltbld', 12:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:15 INFO - 'MOZ_NO_REMOTE': '1', 12:44:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:15 INFO - 'NO_EM_RESTART': '1', 12:44:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:15 INFO - 'PWD': '/builds/slave/test', 12:44:15 INFO - 'SHELL': '/bin/bash', 12:44:15 INFO - 'SHLVL': '1', 12:44:15 INFO - 'TERM': 'linux', 12:44:15 INFO - 'TMOUT': '86400', 12:44:15 INFO - 'USER': 'cltbld', 12:44:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:15 INFO - '_': '/tools/buildbot/bin/python'} 12:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 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)) 12:44:15 INFO - Cleaning up... 12:44:15 INFO - Return code: 0 12:44:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:44:15 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')]} 12:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:15 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')]} 12:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:15 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 0x198b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c29310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, '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': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', '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 12:44:15 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 12:44:15 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 12:44:15 INFO - Using env: {'DISPLAY': ':0', 12:44:15 INFO - 'HOME': '/home/cltbld', 12:44:15 INFO - 'LANG': 'en_US.UTF-8', 12:44:15 INFO - 'LANGUAGE': 'en_US:en', 12:44:15 INFO - 'LOGNAME': 'cltbld', 12:44:15 INFO - 'MAIL': '/var/mail/cltbld', 12:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:15 INFO - 'MOZ_NO_REMOTE': '1', 12:44:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:15 INFO - 'NO_EM_RESTART': '1', 12:44:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:15 INFO - 'PWD': '/builds/slave/test', 12:44:15 INFO - 'SHELL': '/bin/bash', 12:44:15 INFO - 'SHLVL': '1', 12:44:15 INFO - 'TERM': 'linux', 12:44:15 INFO - 'TMOUT': '86400', 12:44:15 INFO - 'USER': 'cltbld', 12:44:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:15 INFO - '_': '/tools/buildbot/bin/python'} 12:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:44:15 INFO - Cleaning up... 12:44:15 INFO - Return code: 0 12:44:15 INFO - Running post-action listener: _resource_record_post_action 12:44:15 INFO - Running post-action listener: _start_resource_monitoring 12:44:15 INFO - Starting resource monitoring. 12:44:15 INFO - [mozharness: 2017-02-17 20:44:15.965062Z] Finished create-virtualenv step (success) 12:44:15 INFO - [mozharness: 2017-02-17 20:44:15.965536Z] Running install step. 12:44:15 INFO - Running pre-action listener: _resource_record_pre_action 12:44:15 INFO - Running main action method: install 12:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:16 INFO - Reading from file tmpfile_stdout 12:44:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:44:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:44:16 INFO - Reading from file tmpfile_stdout 12:44:16 INFO - Output received: 12:44:16 INFO - Usage: mozinstall [options] installer 12:44:16 INFO - Options: 12:44:16 INFO - -h, --help show this help message and exit 12:44:16 INFO - -d DEST, --destination=DEST 12:44:16 INFO - Directory to install application into. [default: 12:44:16 INFO - "/builds/slave/test"] 12:44:16 INFO - --app=APP Application being installed. [default: firefox] 12:44:16 INFO - mkdir: /builds/slave/test/build/application 12:44:16 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'] 12:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:44:31 INFO - Reading from file tmpfile_stdout 12:44:31 INFO - Output received: 12:44:31 INFO - /builds/slave/test/build/application/firefox/firefox 12:44:31 INFO - Running post-action listener: _resource_record_post_action 12:44:31 INFO - [mozharness: 2017-02-17 20:44:31.654426Z] Finished install step (success) 12:44:31 INFO - [mozharness: 2017-02-17 20:44:31.654698Z] Running run-tests step. 12:44:31 INFO - Running pre-action listener: _resource_record_pre_action 12:44:31 INFO - Running main action method: run_tests 12:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:44:31 INFO - Python 2.7.3 12:44:31 INFO - Return code: 0 12:44:31 INFO - grabbing minidump binary from tooltool 12:44:31 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')]} 12:44:31 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 0x1c26400>, '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 0x1c273b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c27840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:44:31 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 12:44:31 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 12:44:31 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 12:44:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:44:31 INFO - Return code: 0 12:44:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:44:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:44:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:44:31 INFO - ENV: RUST_BACKTRACE is now 1 12:44:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:44:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 12:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 12:44:31 INFO - Using env: {'DISPLAY': ':0', 12:44:31 INFO - 'HOME': '/home/cltbld', 12:44:31 INFO - 'LANG': 'en_US.UTF-8', 12:44:31 INFO - 'LANGUAGE': 'en_US:en', 12:44:31 INFO - 'LOGNAME': 'cltbld', 12:44:31 INFO - 'MAIL': '/var/mail/cltbld', 12:44:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:44:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:31 INFO - 'MOZ_NO_REMOTE': '1', 12:44:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:44:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:31 INFO - 'NO_EM_RESTART': '1', 12:44:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:31 INFO - 'PWD': '/builds/slave/test', 12:44:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:44:31 INFO - 'RUST_BACKTRACE': '1', 12:44:31 INFO - 'SHELL': '/bin/bash', 12:44:31 INFO - 'SHLVL': '1', 12:44:31 INFO - 'TERM': 'linux', 12:44:31 INFO - 'TMOUT': '86400', 12:44:31 INFO - 'USER': 'cltbld', 12:44:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379', 12:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:31 INFO - '_': '/tools/buildbot/bin/python'} 12:44:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 12:44:31 INFO - mozversion application_buildid: 20170217201548 12:44:31 INFO - mozversion application_changeset: 797fddcea4e02dab47921f2d2218a93e4a80d9cb 12:44:31 INFO - mozversion application_display_name: Nightly 12:44:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:44:31 INFO - mozversion application_name: Firefox 12:44:31 INFO - mozversion application_remotingname: firefox 12:44:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:44:31 INFO - mozversion application_vendor: Mozilla 12:44:31 INFO - mozversion application_version: 54.0a1 12:44:31 INFO - mozversion platform_buildid: 20170217201548 12:44:31 INFO - mozversion platform_changeset: 797fddcea4e02dab47921f2d2218a93e4a80d9cb 12:44:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:44:31 INFO - mozversion platform_version: 54.0a1 12:44:31 INFO - using testdate: 1487364271 12:44:31 INFO - actual date: 1487364271 12:44:31 INFO - starting webserver on 'localhost:34377' 12:44:31 INFO - SUITE-START | Running 2 tests 12:44:31 INFO - TEST-START | dromaeo_css 12:44:31 INFO - operating with platform_type : linux_ 12:44:31 INFO - Initialising browser for dromaeo_css test... 12:44:31 INFO - TEST-INFO | started process 24186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ_MWR8/profile http://localhost:34377/getInfo.html) 12:44:34 INFO - PROCESS | 24186 | Iterating over profile /tmp/tmpZ_MWR8/profile 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/secmod.db = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | __metrics Screen width/height:1600/1200 12:44:36 INFO - PROCESS | 24186 | colorDepth:24 12:44:36 INFO - PROCESS | 24186 | Browser inner width/height: 1024/697 12:44:36 INFO - PROCESS | 24186 | __metrics 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/safebrowsing = Fri Feb 17 2017 12:44:36 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/extensions = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/content-prefs.sqlite = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/pluginreg.dat = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/crashes = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/thumbnails = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | Stat failure 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/key3.db = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/localstore.rdf = Fri Feb 17 2017 12:43:51 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/places.sqlite-wal = Fri Feb 17 2017 12:44:36 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/bookmarkbackups = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/cache2 = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/places.sqlite = Fri Feb 17 2017 12:44:36 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/user.js = Fri Feb 17 2017 12:44:31 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/directoryLinks.json = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/compatibility.ini = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/.parentlock = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/permissions.sqlite = Fri Feb 17 2017 12:44:33 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/minidumps = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/blocklist.xml = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/frequencyCap.json = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/gmp = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/extensions.ini = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/prefs.js = Fri Feb 17 2017 12:43:51 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/places.sqlite-shm = Fri Feb 17 2017 12:44:33 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/cert8.db = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/sessionCheckpoints.json = Fri Feb 17 2017 12:44:36 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/startupCache = Fri Feb 17 2017 12:44:32 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/extensions.json = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:36 INFO - PROCESS | 24186 | No birth date. Using mtime. 12:44:36 INFO - PROCESS | 24186 | Using date: /tmp/tmpZ_MWR8/profile/addons.json = Fri Feb 17 2017 12:44:34 GMT-0800 (PST) 12:44:37 INFO - PROCESS | 24186 | 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" 12:44:37 INFO - PROCESS | 24186 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:44:38 INFO - TEST-INFO | 24186: exit 0 12:44:38 INFO - Browser initialized. 12:44:38 INFO - Running cycle 1/1 for dromaeo_css test... 12:44:38 INFO - TEST-INFO | started process 24276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ_MWR8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:44:50 INFO - PROCESS | 24276 | RSS: Main: 150364160 12:44:50 INFO - PROCESS | 24276 | 12:47:56 INFO - PROCESS | 24276 | Cycle 1(1): loaded http://localhost:34377/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34377/tests/dromaeo/cssquery-ext.html) 12:47:58 INFO - PROCESS | 24276 | RSS: Main: 267448320 12:47:58 INFO - PROCESS | 24276 | 12:49:40 INFO - PROCESS | 24276 | ************************* 12:49:40 INFO - PROCESS | 24276 | A coding exception was thrown and uncaught in a Task. 12:49:40 INFO - PROCESS | 24276 | 12:49:40 ERROR - PROCESS | 24276 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:49:40 INFO - PROCESS | 24276 | Full stack: 12:49:40 INFO - PROCESS | 24276 | ************************* 12:51:01 INFO - PROCESS | 24276 | Cycle 1(1): loaded http://localhost:34377/tests/dromaeo/cssquery-ext.html (next: http://localhost:34377/tests/dromaeo/cssquery-jquery.html) 12:51:01 INFO - PROCESS | 24276 | RSS: Main: 245264384 12:51:01 INFO - PROCESS | 24276 | 12:54:03 INFO - PROCESS | 24276 | Cycle 1(1): loaded http://localhost:34377/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34377/tests/dromaeo/cssquery-mootools.html) 12:54:04 INFO - PROCESS | 24276 | RSS: Main: 228732928 12:54:04 INFO - PROCESS | 24276 | 12:57:06 INFO - PROCESS | 24276 | Cycle 1(1): loaded http://localhost:34377/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34377/tests/dromaeo/cssquery-prototype.html) 12:57:07 INFO - PROCESS | 24276 | RSS: Main: 183156736 12:57:07 INFO - PROCESS | 24276 | 13:00:10 INFO - PROCESS | 24276 | Cycle 1(1): loaded http://localhost:34377/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34377/tests/dromaeo/cssquery-yui.html) 13:00:11 INFO - PROCESS | 24276 | RSS: Main: 183648256 13:00:11 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | Cycle 1(1): loaded http://localhost:34377/tests/dromaeo/cssquery-yui.html (next: http://localhost:34377/tests/dromaeo/cssquery-dojo.html) 13:03:14 INFO - PROCESS | 24276 | RSS: Main: 275582976 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | __start_tp_report 13:03:14 INFO - PROCESS | 24276 | _x_x_mozilla_page_load 13:03:14 INFO - PROCESS | 24276 | _x_x_mozilla_page_load_details 13:03:14 INFO - PROCESS | 24276 | |i|pagename|runs| 13:03:14 INFO - PROCESS | 24276 | |0;dojo.html;2573.98713006435;2599;2616.3459135216203;2626.9080582179627;2633.9078132265377;4547.158775626512;4610.769461526927;4698.953010469894;4714.528547145288;4721.669483136182;4693;4715.811367545294;4741.834035808747;4754.595859351951;4832.516748325165;4283.485981682203;4374.60628543431;4389.078293558357;4408.735475871442;4438.267685831834;4429.136318417926;4468.374427580109;4484.1928452878465;4487.618552423048;4510.188166130095;4277.764722940237;4294.935575966363;4304.375865499516;4356.3683265926265;4363.956360436387;4372.956270437287;4405.977970110161;4410.669199809995;4464.196444639963;4482.260427029536;4377.912441751179;4382.956170438287;4401.713888597231;4407.537208593084;4441.511433742286;4617.838375656869;4624.167649823031;4650.372199753024;4667.113248482778;4705.1530724469585;5059.772310246048;5060.316857224265;5061.822836200715;5065.113605119126;5065.493450654942;5056.82301119459;5076.44159142494;5096.770645320969;5099.898002039976;5105.463926287724;4745.738984355859;4817.759112044454;4820.493848145964;4824.252240902666;4826.083044221657;5218.191180367049;5218.7912483500995;5219.634625576249;5221.921671174935;5260.684358938476;4820.783064762094;4871.318015477836;4932.309476673268;5000.64995450315;5010.298558201855;5018.24726291052;5030.144875371196;5074.213496907985;5075.670081444694;5081.18701007837;4937.259411088366;5051.393832740095;5054.7472626368535;5071.163258062378;5114.48855114493;4976;4989.351384320015;5036;5061.670991385548;5064.088464076428;4317.417148684887;4320.632746216606;4332.610065094157;4332.978335108304;4333.783310834446;3714.832832522543;3727.7390582659496;3748.025513366544;3788.924221515554;3790.223004284115;4446.510883802844;4448.488423831236;4450.087732014929;4450.5549445055885;4500.8649740507835;5128.640995130305;5136.974315128476;5139.511746384088;5142.640015198975;5201.973990130101;4287.7641729705165;4313.288307429302;4450.310201918708;4483.14820184164;4487.237169681162;4669.066186762661;4689.343491911135;4721;4726.007538416971;4727.527247275245;4851.660383773173;4906.190478571139;4906.6320025997475;4910.901781964412;4941.110600092018;4938.111139994692;4990.6506544541535;5208.453112423293;5214.165733482625;5217.34783152106;4745.311929770233;4758.738269395078;4761.880952976203;4766.833160839354;4803.471618121934;5120.385553733576;5120.974395128001;5122.948770512247;5127.53852153308;5130.7178105204575;5150.562202212704;5153.948460515347;5154.61340399479;5155.943031678422;5158.948410515997;5017.3226614406585;5018.1469150245275;5019.272205530251;5021.799128034838;5064.797408103635;4765.9523404765505;4828.7102773833685;4856.320115183948;4870.4155501340065;4956.207006879026;4889.706617602955;4940.580050695731;4941;4968.652194346506;4995.525425084611;5031.597472202288;5032.572231360229;5035.899282014412;5041.672291301201;5083.034223497523;4630.467496237976;4673.696209746492;4917.606591472745;4925.088858561177;4925.901481970269 13:03:14 INFO - PROCESS | 24276 | |1;ext.html;546.5791340667686;560.963537370071;566.2214455124201;577.1948132355362;595.8152972578498;27402.862985685068;38330.61669383305;40207.195856082864;40231.19537609246;40396.19207615846;39130;39401;39565.61520346788;39574;39587.208255834936;7538;7715.614219289041;7754.185810489902;7798.883016754753;7815.921840781604;6831.248562658104;7100.6449677516175;9536.284778641595;9856.655017074403;9878.357906736039;9712.174465170468;9731.318807683465;9746;9760.4631745254;9767.316287859852;9737.221022318234;9797.363171393836;9797.608095676165;9803.166730827888;9804.803903921953;9498.905010949871;9575.186109180659;9575.664851730156;9576.425414475085;9576.856347154799;35828;36307.63692363069;36321.81839090814;36450.45324320137;36563.268734625155;3506.9824650876835;3523.894283171509;3532.7181834009157;3573.76770509916;3707.2400157967554;26884.865575671996;26924.192274231806;28346.433071338663;28425.43149137006;28750.281242968886;3169.3344397676515;3220.8872689455993;3298.5546951161527;3316.4527852904243;3375.6455572164814;216736.99156101368;216941;217202;217461;218370;42003;50101.49898501041;50741.23888141681;50746.23880641793;50752.238716419284;45422;45618.77190614026;45928.3110753339;53013.204801928005;54331.491988901784;11547.884521154849;11780.764384712258;11817.940910295394;11818.822717659243;11832.585859494877;7847.332976696931;8703.6518539259;8766.123387661182;8769.693060742844;8783.560821958876;12720.93639531815;14742.189179595225;15162.848371516144;15178.924105379403;15718.21408929549;5566.749496272678;5568.192612071227;5616.85957851057;5730.942690573124;5740.971295143498;770.9845803084019;781.1719577248135;781.8162731758032;799.476342995339;810.2262339465796;26293.28196965073;26498.735012650013;26629;26843.731562684512;26943.86528067347;901.7565257380602;909.1862782809349;910.1080940678172;913.3287034029983;931.7484279244566;13911.30443477822;14222.715545689236;14337.498187563386;14489.420423183166;14618.926905365617;319402;321987;322123;322354;322627.8321035057;46651.76674116608;46801.765991170505;46818.5318146821;46838;46910.765446172554;1151.8560179977503;1156.4564654612318;1174.8237764335418;1185.140772939612;1187.2876274235562;24547.38631534226;25315.367115822253;25790.484190316463;25965.22104336873;26046.73953260443;39214.607853922236;39796.40305395363;39798.60201398065;39827.40258896177;39860.601393985686;274871;275453;276720;277099;278531;27925.441491169655;32634.34731305408;32775.836120819244;33187.17032074121;33381.83309083536;32372.514412283363;32538.186545336557;39886;42712.57287427086;43297.35053974256;34394.31211375808;35058.64941350554;36938.97416697866;39162.60837391589;39851;35222.47166292559;35337.64662353344;35810.28379432449;35871.46192807058;36197.276054478236;35825;35835.82082089573;35946;36442.8177859109;37102.257954840214;30352.69647303587;32520.551623988922;32757.34485310328;33202.667973319956;33300.333993320484;24610.261692149263;24924.875375623007;25091.87454062718;25122.49755004853;25516.48967020686 13:03:14 INFO - PROCESS | 24276 | |2;jquery.html;356.52938121679364;370.2447008103467;389.1885418901591;390.9648131668149;405.58225028220943;12383.814242786355;13247.867521324784;13455.730885382287;17501.299948002066;18470.44588662339;16423.589410264718;18351.816481835176;18652;18768.061596920168;18790.53023674409;4955.554000139978;5194.324737784102;5204.037253108168;5209.583233341343;5210.062188806013;5038.571721403685;5128;5135.666181698196;5182.041322355376;5216.321878155834;5140;5144.717040562768;5146.176611742122;5158.071547121516;5164.845054648366;4993.7503124843615;5038.042771873369;5092.28707980883;5127.5128862758165;5131.589472842183;4556.954430455686;4566.634669226454;4578.931316030262;4581.129585378767;4611.054733779567;20473.692894606593;21226.25708100209;21306.14775408982;21323.253686121065;21422.78577214239;2233.3188377544843;2272.9659055114184;2292.86242825431;2314.6065168921323;2318.6406107053435;13262.801057984138;13385.86614133856;13506.527271545547;13627.114237574626;14339.78490322646;1685.1742646103444;1685.6375879186035;1686.7048266553427;1687.7890263717036;1723.1470422140994;59908.40091599115;64120;73247.63376183138;73250.26749732488;74741.62629186819;9947.253955953332;10101.747456313651;10112.443815590213;10410.115140213014;10518.263721539572;14016.789748153786;14038.508652197124;14057.367418466194;14061.367238474293;14075.225862577452;6344.460720838781;6413.8717225656155;6481.0602462642655;6643.302453242437;6662.800115996528;5651.886962260732;5840.883182336414;5846.239988801429;5849.94150058494;5852.736626851802;6399.680015999182;6684.665766711645;6685.532012759158;6701.056693558813;6718.193816741925;1762.9559261018321;1768.734689796515;1778.2797966823457;1781.2251670523365;1784.2060283174123;785.615048626169;797.9760607181794;799.1409235072321;804.3645520039104;810.720301495983;16976.151192440328;17663.823361766474;18165.636687266182;18261.360852370104;18512.352067677566;771.4715419937361;784.7018133109497;794.7814351053391;799.6121880887765;805.1505661527145;9378.155965963097;10161.745956351151;10166.237532185116;10257.33327333721;10406.427646479517;452263;454433;456258;459310;465368;59579.40420595825;73467;74891.62554187303;74924.62537687276;76498.61750691211;883.9071897450804;906.3338446242007;930.6835675870101;936.4194199596158;936.798588303518;15078.472253471076;15162.469313573973;15686.372545098067;15846.44537441184;15967.361305547647;34609.65390346165;34636.96089117331;34705.82647086748;35504.11239719028;35642;45617.31574026459;46509.53490465052;46926;46957.295640564735;47175;7672.5012874163;7748.418868584766;7804;7843.372530197685;8036.959815200887;12353;12417.813732794197;12434.56479023266;12503.312317822607;12552.560660376843;10224.53989570486;10449;10463.895361046292;10639.946800265949;10837;10421.218408619536;10441.68674939753;10444.895551044696;10606.946965265124;10736.30214036085;10489.685309440729;10498.895011050097;10627.36235825822;10692.89307106919;10837.67486975392;7787.92212077872;7796.688132474865;7831.686732530635;7928.41431713648;7932.722354717557;10377.16982641402;10412.895871041495;10488.423136727553;10544.156467482737;10717.196210284415 13:03:14 INFO - PROCESS | 24276 | |3;mootools.html;1187.180845216801;1313.0152385710717;1318.578055022392;1323.4772264955343;1327.1572551429842;6215.689215539222;6951.826204344888;7085.397741192005;7128.215896251415;7357.632118394085;7198.388137008361;7267.454940879429;7269.3094156055095;7280.089988751406;7284.672189751461;2031.2890488329078;2061.8556701030934;2064.4735592423913;2064.5354795171115;2064.7006184103298;1784.3843873863495;1835.3667984545377;2066.5350296183387;2143.239150101707;2148.205164089289;2088.2899814063217;2088.446561661161;2091.654876945302;2095.0886364431444;2097.590969760897;1998.15078591598;2005.4384772263716;2019.8384129269623;2020.3837829462009;2028.8579799414047;2007.9498012549802;2016.9294074707461;2069.2343832781967;2071.461420030803;2073.6993135995194;7299.124105107368;7362.484626076177;7363.815904602374;7365.963170184168;7368.336849683555;4309.762963037032;4430.623397011258;4432.15789000089;4434.600885920283;4434.889127771799;2781.332976752174;2888.321244507539;2899.144752298077;2944.5730369096577;2946.7053294670577;1108.905743011845;1157.4675649201376;1159.4087015622008;1164.56852388706;1182.2197349749124;9779.706608801747;9785.412875227437;9870.111689947867;9908.554115064839;9917.801643967152;3868.4197370394104;4107.97946010274;4117.711760176759;4167.583021772612;4168.687348448879;8289.709860154866;8298.170182981654;8301.169883011651;8303.501789892505;8309.37679674027;4506.324051392252;4559.52125026874;4561.657875659339;4562.977185114053;4574.565416285448;4039.151778126626;4107.835686572504;4163.812628431728;4170.541240463546;4173.874783756492;3397.779144355609;3408.9659103409144;3574.7318951078773;3586.8565257389414;3599.46008098782;1116.017904244276;1161.814109742437;1169.6491052684162;1170.4791367841399;1177.9116566257394;791.1416113516783;803.2609998801117;812.0580127052591;816.8284660221301;820.274057459141;4620.214563524209;4676.883077923079;4787.61699064074;4834;4903.901921961541;524.4336116993637;529.9602529810203;533.8745394832212;537.965032272901;538.2787065332474;2977.389635124794;3006.3686625808386;3016.6078409807023;3088.166195127303;3092.721655051056;8316.916830831735;8557.871631925525;8611.440256383315;8756.343274254456;8761.518116503654;8643.567821608895;8676.08901065379;8677.349198810114;8689.435186712843;8715.346349023848;6590.9670451647435;6781.287964763727;6844.2129155147795;6847.349501797321;6858.725650974005;4695.389599352063;4701.788419521198;4707.576318131385;4768.451628062817;4792;7826.843463130819;7841.686332546634;7962.203779622223;8043.396745244013;8050.758477245692;7966.123726389977;8081.070676872;8166.346692264723;8198.918010819816;8202.83594328122;5671.602987790815;5972.313183983897;6251.4061164189325;6302.432781049728;6314.242290925118;7073.363397294268;7135.571865688075;7140.285971402818;7146.106736657918;7149;6203.31763506023;6275.3724627536885;6276.089966954857;6291.874162516816;6465.579737317059;6182.629042257479;6187.721552530237;6214.378562143749;6219.191505104308;6365.2361716594305;5506.174073888948;5585.748641311069;5588.077967135539;5593;5596.440355964371;4252.596003379674;4287.721298115737;4315.4605674290715;4337.7614231217585;4389.144116897174;4074.1851629673592;4208.684348673923;4221.535631080517;4246.299360605589;4267.381229721611 13:03:14 INFO - PROCESS | 24276 | |4;prototype.html;371.894010207091;371.90888232383054;373.18645353130177;373.5573345585481;374.923140756145;4721.12659158056;4738.502457241987;4787.186178349681;4801.782805520301;4844.1038407894475;4713.222318317482;4730.148573256821;4746.881327966803;4770.189067858464;4792.089502994429;3631.4008188648972;3725.8449880536987;3743.28877513273;3747.1194269346684;3792.127810603563;3752.8686495972647;3760.717946154036;3772.6793222576116;3774.1508160663907;3783.678387337066;3738.5513738351437;3750.4374343848367;3758.68051215647;3759.154190307186;3770.2836461072447;3675.1547144156725;3755.6432138946757;3757.4927384803113;3780.262848744496;3803.6386543278345;3288.4409650359257;3295.653956334598;3296;3296.626428971137;3319.6348401675796;4664.790084446208;4672.495628278791;4685.2269375553;4693.460252071021;4698.530146985308;4232.280512312914;4236.449261596003;4238.427812245338;4250.91498170038;4268.250017492813;4747.7626118693925;4919.2129259318335;4970.577500912426;4974.228994505821;5022.372203474566;3580.4629305604108;3595.4359853463825;3604.477350784148;3617.2946275476293;3618.9819050904575;4962.9007419851405;5069.923951140736;5134.743262836843;5239.175401956593;5311.442298558673;3600.2979419013564;3605.531280933462;3614.981925090358;3625.3474374612297;3639.636036396339;3650.963490365062;3657.6890964268337;3669.8439991402624;3673.081729567608;3676.191237927652;3995.4606128172904;4101.384792281182;4202.243596152722;4209.421991307426;4211.515206028458;4720.88197795051;4957.355543779286;4971.776270067856;4973.35346404965;5014.548690617862;1233.2970206981934;1235.690168421476;1244.7759403307311;1248.4382028087398;1262.5707259531803;806.9878951815728;856.4518708026868;857.8327226190833;875.2166810704384;876.250805561236;4278.764667943293;4280.507741609755;4291.206126866539;4299.215825432473;4303.376010478463;4928.609624332196;4937.901241975141;5004.598712360545;5010.49895010496;5012.348394708737;4261.232978063917;4299.269124248885;4306.375575541529;4328.870133895988;4354.825806967686;2503.7996960243136;2621.934451638686;2629.6975847777367;2640.6435131257417;2655.5751079827523;4676.649251306166;4697.459792123949;4701.177293973541;4721.662776925444;4725.818545363659;5042.949570504321;5084.669496482717;5088.821891233789;5096.413912400121;5110.821121260738;4558.202314594932;4832.685875418087;4870.342503761947;4879.853604391874;4886.462489126265;5150.81090536528;5164.767585458589;5171.3535808024;5190.351206099238;5208.479152084761;4888.87334009707;5101.591872650105;5105.2342148678;5123.74381280927;5135.15269980449;4642.257238841769;4732.621390288699;4831.4685384608365;4855.067395409729;4862.811734719319;3541.3802584547084;3572.285542891484;3580.7135429165082;3599.892003239907;3690.1697118148204;5142.305788718625;5165.457626949266;5179.472055743489;5180.736136234148;5197.454267301879;4886.169351210232;5016.698998060128;5020.673656212334;5039.168537191323;5055.443901170795;4931;4975.676581022222;4977.117848078505;4977.153883839828;4986;4901.803927842989;4922.310876477224;4935.75321233944;4966.205407134841;4975.7014579123925;4742.620590352694;4951.638299467675;5003.599712023102;5016.147254966738;5035.672681275705;4902.3872015998;4902.509749025069;4914.238293064581;4916;4928.310036594951 13:03:14 INFO - PROCESS | 24276 | |5;yui.html;679.9728010879566;681.696644992978;728.6757392960131;740.9259074092591;751.2262369759146;12327.753444931108;12879.54921577745;13153.342332883365;13158.802617960739;13168.407421666025;12735.363231838393;12797.976161907049;12849.614511564645;12855.292958887281;12940.676483087927;817.1215942861418;832.0556168748484;842.3008902610818;843.3211264931728;843.9704610338639;849.613425891218;851.3870013590205;851.5699571716275;851.7572491839827;852.0797538658265;883.8674198870157;886.8625363068702;886.8935727712684;887.024273299372;887.2458410351214;871.1941454154913;876.1370050500252;876.3427429427929;880.4453194487464;880.5861245214771;651.6774196772628;653.7090994507446;653.8757636049136;654.8428377189488;655.4756195043993;13964.860351396457;14156.150630962175;14301.856981430157;14302.356393962296;14559.78160327596;13447.731045379136;13662.108986843667;13995.30023498821;14033.929830350886;14094.295285235698;10700.357978521313;10703.732406689816;10734.53922637629;11037.668869933916;11344.546218151265;3158.7157155855853;3159.920629000257;3160.7471402287993;3170.6036745406823;3188.2666986593213;58280.417195827926;58329.416705833246;58474.70762646202;58971;60403.69798150981;9683.305745567672;9768.21854251658;9803.313768036312;9896.653617123366;10027.649032283834;19483.902580487007;19499.512512187026;19535.511612209808;19548.31580894662;19773.901130494254;5625.46558076982;5768.94231057684;5887.234659494238;5907.881842363215;5912.231409916683;3362.36115138123;3434.708049815744;3483.7387195960405;3498.352804731133;3558;3038.5745995560637;3041.4525385430393;3101.5347697845505;3109.5646609474693;3130.4834702274325;497.7685376300038;498.2526210683974;503.9672421292604;515.4407467897373;515.6673945305257;633.3603060908515;633.9270983836825;654.4339146638218;659.4065341192965;664.1598378051768;8961.969373521997;8971.865422018675;9000.189982901571;9240.306976976754;9339;639.3638329861817;668.5353679193012;669.2103318084706;672.7140965089817;674.5918719174829;4028.718194002269;4096.32410652245;4358.346248062752;4358.346248062816;4402.955970440319;60404;61085.69457152686;62286.68856655688;64590;64666.67666661637;53030.204546931825;54060.459395405545;54164.72917635465;55116.44883551193;55280.723596382566;45057.32414013793;45456.54543454589;46114.308285375744;46826;47912.7604361976;8748.65005399777;9258.259339252978;9370.437773733598;9708.854367184356;9713.125818676353;29059.7094029057;29325.413491729618;29357.706422936353;30233.546496802126;33737.493937590465;35607.82196089003;40594.94050594917;41985.58014419939;43482.34776478292;44491.555084448744;11314.60398886035;11454.541818327174;11545;11545.538178472767;11573.594924177612;12881.871181288067;12990.220586764824;13007.154534955198;13095.333306674147;13228.41344108899;11543.769124617293;11705.88294117082;12036.916677499066;12102;12105.576304829288;11640.301581905112;12026.819597705866;12327.075469339656;12584.433700483318;12587.68530786701;11357.261777984404;11460.14048946349;11590.88409115898;11667.474963626826;11726;9579.271344953871;9807.313488055768;9924.255680824112;10034.648787292701;10233.334833236113;8587.853036740815;8752.51861147643;9541.757686943702;9898.851517227391;10017.749556261151 13:03:14 INFO - PROCESS | 24276 | __end_tp_report 13:03:14 INFO - PROCESS | 24276 | __start_cc_report 13:03:14 INFO - PROCESS | 24276 | _x_x_mozilla_cycle_collect,1412 13:03:14 INFO - PROCESS | 24276 | __end_cc_report 13:03:14 INFO - PROCESS | 24276 | __startTimestamp1487365394540__endTimestamp 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | ------- Summary: start ------- 13:03:14 INFO - PROCESS | 24276 | Number of tests: 6 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | [#0] dojo.html Cycles:165 Average:4711.12 Median:4826.08 stddev:499.84 (10.4%) stddev-sans-first:472.42 13:03:14 INFO - PROCESS | 24276 | Values: 2574.0 2599.0 2616.3 2626.9 2633.9 4547.2 4610.8 4699.0 4714.5 4721.7 4693.0 4715.8 4741.8 4754.6 4832.5 4283.5 4374.6 4389.1 4408.7 4438.3 4429.1 4468.4 4484.2 4487.6 4510.2 4277.8 4294.9 4304.4 4356.4 4364.0 4373.0 4406.0 4410.7 4464.2 4482.3 4377.9 4383.0 4401.7 4407.5 4441.5 4617.8 4624.2 4650.4 4667.1 4705.2 5059.8 5060.3 5061.8 5065.1 5065.5 5056.8 5076.4 5096.8 5099.9 5105.5 4745.7 4817.8 4820.5 4824.3 4826.1 5218.2 5218.8 5219.6 5221.9 5260.7 4820.8 4871.3 4932.3 5000.6 5010.3 5018.2 5030.1 5074.2 5075.7 5081.2 4937.3 5051.4 5054.7 5071.2 5114.5 4976.0 4989.4 5036.0 5061.7 5064.1 4317.4 4320.6 4332.6 4333.0 4333.8 3714.8 3727.7 3748.0 3788.9 3790.2 4446.5 4448.5 4450.1 4450.6 4500.9 5128.6 5137.0 5139.5 5142.6 5202.0 4287.8 4313.3 4450.3 4483.1 4487.2 4669.1 4689.3 4721.0 4726.0 4727.5 4851.7 4906.2 4906.6 4910.9 4941.1 4938.1 4990.7 5208.5 5214.2 5217.3 4745.3 4758.7 4761.9 4766.8 4803.5 5120.4 5121.0 5122.9 5127.5 5130.7 5150.6 5153.9 5154.6 5155.9 5158.9 5017.3 5018.1 5019.3 5021.8 5064.8 4766.0 4828.7 4856.3 4870.4 4956.2 4889.7 4940.6 4941.0 4968.7 4995.5 5031.6 5032.6 5035.9 5041.7 5083.0 4630.5 4673.7 4917.6 4925.1 4925.9 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | [#1] ext.html Cycles:180 Average:42785.47 Median:36446.64 stddev:71995.82 (197.5%) stddev-sans-first:72127.94 13:03:14 INFO - PROCESS | 24276 | Values: 546.6 561.0 566.2 577.2 595.8 27402.9 38330.6 40207.2 40231.2 40396.2 39130.0 39401.0 39565.6 39574.0 39587.2 7538.0 7715.6 7754.2 7798.9 7815.9 6831.2 7100.6 9536.3 9856.7 9878.4 9712.2 9731.3 9746.0 9760.5 9767.3 9737.2 9797.4 9797.6 9803.2 9804.8 9498.9 9575.2 9575.7 9576.4 9576.9 35828.0 36307.6 36321.8 36450.5 36563.3 3507.0 3523.9 3532.7 3573.8 3707.2 26884.9 26924.2 28346.4 28425.4 28750.3 3169.3 3220.9 3298.6 3316.5 3375.6 216737.0 216941.0 217202.0 217461.0 218370.0 42003.0 50101.5 50741.2 50746.2 50752.2 45422.0 45618.8 45928.3 53013.2 54331.5 11547.9 11780.8 11817.9 11818.8 11832.6 7847.3 8703.7 8766.1 8769.7 8783.6 12720.9 14742.2 15162.8 15178.9 15718.2 5566.7 5568.2 5616.9 5730.9 5741.0 771.0 781.2 781.8 799.5 810.2 26293.3 26498.7 26629.0 26843.7 26943.9 901.8 909.2 910.1 913.3 931.7 13911.3 14222.7 14337.5 14489.4 14618.9 319402.0 321987.0 322123.0 322354.0 322627.8 46651.8 46801.8 46818.5 46838.0 46910.8 1151.9 1156.5 1174.8 1185.1 1187.3 24547.4 25315.4 25790.5 25965.2 26046.7 39214.6 39796.4 39798.6 39827.4 39860.6 274871.0 275453.0 276720.0 277099.0 278531.0 27925.4 32634.3 32775.8 33187.2 33381.8 32372.5 32538.2 39886.0 42712.6 43297.4 34394.3 35058.6 36939.0 39162.6 39851.0 35222.5 35337.6 35810.3 35871.5 36197.3 35825.0 35835.8 35946.0 36442.8 37102.3 30352.7 32520.6 32757.3 33202.7 33300.3 24610.3 24924.9 25091.9 25122.5 25516.5 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | [#2] jquery.html Cycles:180 Average:26081.16 Median:390.08 stddev:75008.19 (19229.1%) stddev-sans-first:75193.74 13:03:14 INFO - PROCESS | 24276 | Values: 356.5 370.2 389.2 391.0 405.6 12383.8 13247.9 13455.7 17501.3 18470.4 16423.6 18351.8 18652.0 18768.1 18790.5 4955.6 5194.3 5204.0 5209.6 5210.1 5038.6 5128.0 5135.7 5182.0 5216.3 5140.0 5144.7 5146.2 5158.1 5164.8 4993.8 5038.0 5092.3 5127.5 5131.6 4557.0 4566.6 4578.9 4581.1 4611.1 20473.7 21226.3 21306.1 21323.3 21422.8 2233.3 2273.0 2292.9 2314.6 2318.6 13262.8 13385.9 13506.5 13627.1 14339.8 1685.2 1685.6 1686.7 1687.8 1723.1 59908.4 64120.0 73247.6 73250.3 74741.6 9947.3 10101.7 10112.4 10410.1 10518.3 14016.8 14038.5 14057.4 14061.4 14075.2 6344.5 6413.9 6481.1 6643.3 6662.8 5651.9 5840.9 5846.2 5849.9 5852.7 6399.7 6684.7 6685.5 6701.1 6718.2 1763.0 1768.7 1778.3 1781.2 1784.2 785.6 798.0 799.1 804.4 810.7 16976.2 17663.8 18165.6 18261.4 18512.4 771.5 784.7 794.8 799.6 805.2 9378.2 10161.7 10166.2 10257.3 10406.4 452263.0 454433.0 456258.0 459310.0 465368.0 59579.4 73467.0 74891.6 74924.6 76498.6 883.9 906.3 930.7 936.4 936.8 15078.5 15162.5 15686.4 15846.4 15967.4 34609.7 34637.0 34705.8 35504.1 35642.0 45617.3 46509.5 46926.0 46957.3 47175.0 7672.5 7748.4 7804.0 7843.4 8037.0 12353.0 12417.8 12434.6 12503.3 12552.6 10224.5 10449.0 10463.9 10639.9 10837.0 10421.2 10441.7 10444.9 10606.9 10736.3 10489.7 10498.9 10627.4 10692.9 10837.7 7787.9 7796.7 7831.7 7928.4 7932.7 10377.2 10412.9 10488.4 10544.2 10717.2 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | [#3] mootools.html Cycles:180 Average:4749.51 Median:4778.03 stddev:2604.46 (54.5%) stddev-sans-first:2598.01 13:03:14 INFO - PROCESS | 24276 | Values: 1187.2 1313.0 1318.6 1323.5 1327.2 6215.7 6951.8 7085.4 7128.2 7357.6 7198.4 7267.5 7269.3 7280.1 7284.7 2031.3 2061.9 2064.5 2064.5 2064.7 1784.4 1835.4 2066.5 2143.2 2148.2 2088.3 2088.4 2091.7 2095.1 2097.6 1998.2 2005.4 2019.8 2020.4 2028.9 2007.9 2016.9 2069.2 2071.5 2073.7 7299.1 7362.5 7363.8 7366.0 7368.3 4309.8 4430.6 4432.2 4434.6 4434.9 2781.3 2888.3 2899.1 2944.6 2946.7 1108.9 1157.5 1159.4 1164.6 1182.2 9779.7 9785.4 9870.1 9908.6 9917.8 3868.4 4108.0 4117.7 4167.6 4168.7 8289.7 8298.2 8301.2 8303.5 8309.4 4506.3 4559.5 4561.7 4563.0 4574.6 4039.2 4107.8 4163.8 4170.5 4173.9 3397.8 3409.0 3574.7 3586.9 3599.5 1116.0 1161.8 1169.6 1170.5 1177.9 791.1 803.3 812.1 816.8 820.3 4620.2 4676.9 4787.6 4834.0 4903.9 524.4 530.0 533.9 538.0 538.3 2977.4 3006.4 3016.6 3088.2 3092.7 8316.9 8557.9 8611.4 8756.3 8761.5 8643.6 8676.1 8677.3 8689.4 8715.3 6591.0 6781.3 6844.2 6847.3 6858.7 4695.4 4701.8 4707.6 4768.5 4792.0 7826.8 7841.7 7962.2 8043.4 8050.8 7966.1 8081.1 8166.3 8198.9 8202.8 5671.6 5972.3 6251.4 6302.4 6314.2 7073.4 7135.6 7140.3 7146.1 7149.0 6203.3 6275.4 6276.1 6291.9 6465.6 6182.6 6187.7 6214.4 6219.2 6365.2 5506.2 5585.7 5588.1 5593.0 5596.4 4252.6 4287.7 4315.5 4337.8 4389.1 4074.2 4208.7 4221.5 4246.3 4267.4 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | [#4] prototype.html Cycles:180 Average:4128.54 Median:4717.05 stddev:1190.00 (25.2%) stddev-sans-first:1159.46 13:03:14 INFO - PROCESS | 24276 | Values: 371.9 371.9 373.2 373.6 374.9 4721.1 4738.5 4787.2 4801.8 4844.1 4713.2 4730.1 4746.9 4770.2 4792.1 3631.4 3725.8 3743.3 3747.1 3792.1 3752.9 3760.7 3772.7 3774.2 3783.7 3738.6 3750.4 3758.7 3759.2 3770.3 3675.2 3755.6 3757.5 3780.3 3803.6 3288.4 3295.7 3296.0 3296.6 3319.6 4664.8 4672.5 4685.2 4693.5 4698.5 4232.3 4236.4 4238.4 4250.9 4268.3 4747.8 4919.2 4970.6 4974.2 5022.4 3580.5 3595.4 3604.5 3617.3 3619.0 4962.9 5069.9 5134.7 5239.2 5311.4 3600.3 3605.5 3615.0 3625.3 3639.6 3651.0 3657.7 3669.8 3673.1 3676.2 3995.5 4101.4 4202.2 4209.4 4211.5 4720.9 4957.4 4971.8 4973.4 5014.5 1233.3 1235.7 1244.8 1248.4 1262.6 807.0 856.5 857.8 875.2 876.3 4278.8 4280.5 4291.2 4299.2 4303.4 4928.6 4937.9 5004.6 5010.5 5012.3 4261.2 4299.3 4306.4 4328.9 4354.8 2503.8 2621.9 2629.7 2640.6 2655.6 4676.6 4697.5 4701.2 4721.7 4725.8 5042.9 5084.7 5088.8 5096.4 5110.8 4558.2 4832.7 4870.3 4879.9 4886.5 5150.8 5164.8 5171.4 5190.4 5208.5 4888.9 5101.6 5105.2 5123.7 5135.2 4642.3 4732.6 4831.5 4855.1 4862.8 3541.4 3572.3 3580.7 3599.9 3690.2 5142.3 5165.5 5179.5 5180.7 5197.5 4886.2 5016.7 5020.7 5039.2 5055.4 4931.0 4975.7 4977.1 4977.2 4986.0 4901.8 4922.3 4935.8 4966.2 4975.7 4742.6 4951.6 5003.6 5016.1 5035.7 4902.4 4902.5 4914.2 4916.0 4928.3 13:03:14 INFO - PROCESS | 24276 | 13:03:14 INFO - PROCESS | 24276 | [#5] yui.html Cycles:180 Average:14229.17 Median:45785.43 stddev:16986.27 (37.1%) stddev-sans-first:17003.45 13:03:14 INFO - PROCESS | 24276 | Values: 680.0 681.7 728.7 740.9 751.2 12327.8 12879.5 13153.3 13158.8 13168.4 12735.4 12798.0 12849.6 12855.3 12940.7 817.1 832.1 842.3 843.3 844.0 849.6 851.4 851.6 851.8 852.1 883.9 886.9 886.9 887.0 887.2 871.2 876.1 876.3 880.4 880.6 651.7 653.7 653.9 654.8 655.5 13964.9 14156.2 14301.9 14302.4 14559.8 13447.7 13662.1 13995.3 14033.9 14094.3 10700.4 10703.7 10734.5 11037.7 11344.5 3158.7 3159.9 3160.7 3170.6 3188.3 58280.4 58329.4 58474.7 58971.0 60403.7 9683.3 9768.2 9803.3 9896.7 10027.6 19483.9 19499.5 19535.5 19548.3 19773.9 5625.5 5768.9 5887.2 5907.9 5912.2 3362.4 3434.7 3483.7 3498.4 3558.0 3038.6 3041.5 3101.5 3109.6 3130.5 497.8 498.3 504.0 515.4 515.7 633.4 633.9 654.4 659.4 664.2 8962.0 8971.9 9000.2 9240.3 9339.0 639.4 668.5 669.2 672.7 674.6 4028.7 4096.3 4358.3 4358.3 4403.0 60404.0 61085.7 62286.7 64590.0 64666.7 53030.2 54060.5 54164.7 55116.4 55280.7 45057.3 45456.5 46114.3 46826.0 47912.8 8748.7 9258.3 9370.4 9708.9 9713.1 29059.7 29325.4 29357.7 30233.5 33737.5 35607.8 40594.9 41985.6 43482.3 44491.6 11314.6 11454.5 11545.0 11545.5 11573.6 12881.9 12990.2 13007.2 13095.3 13228.4 11543.8 11705.9 12036.9 12102.0 12105.6 11640.3 12026.8 12327.1 12584.4 12587.7 11357.3 11460.1 11590.9 11667.5 11726.0 9579.3 9807.3 9924.3 10034.6 10233.3 8587.9 8752.5 9541.8 9898.9 10017.7 13:03:14 INFO - PROCESS | 24276 | -------- Summary: end -------- 13:03:14 INFO - PROCESS | 24276 | 13:03:15 INFO - TEST-INFO | 24276: exit 0 13:03:15 INFO - TEST-OK | dromaeo_css | took 1123480ms 13:03:15 INFO - TEST-START | kraken 13:03:15 INFO - operating with platform_type : linux_ 13:03:15 INFO - Initialising browser for kraken test... 13:03:15 INFO - TEST-INFO | started process 24591 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphQcfDe/profile http://localhost:34377/getInfo.html) 13:03:18 INFO - PROCESS | 24591 | Iterating over profile /tmp/tmphQcfDe/profile 13:03:19 INFO - PROCESS | 24591 | __metrics Screen width/height:1600/1200 13:03:19 INFO - PROCESS | 24591 | colorDepth:24 13:03:19 INFO - PROCESS | 24591 | Browser inner width/height: 1024/697 13:03:19 INFO - PROCESS | 24591 | __metrics 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/cookies.sqlite = Fri Feb 17 2017 13:03:18 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/mimeTypes.rdf = Fri Feb 17 2017 13:03:19 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/secmod.db = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/safebrowsing = Fri Feb 17 2017 13:03:19 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/extensions = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/content-prefs.sqlite = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/pluginreg.dat = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/crashes = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/thumbnails = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | Stat failure 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/key3.db = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/localstore.rdf = Fri Feb 17 2017 12:43:51 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/places.sqlite-wal = Fri Feb 17 2017 13:03:19 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/bookmarkbackups = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/cache2 = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/places.sqlite = Fri Feb 17 2017 13:03:19 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/user.js = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/directoryLinks.json = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/compatibility.ini = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/.parentlock = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/permissions.sqlite = Fri Feb 17 2017 13:03:16 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/minidumps = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/blocklist.xml = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/frequencyCap.json = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/gmp = Fri Feb 17 2017 13:03:16 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/extensions.ini = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/prefs.js = Fri Feb 17 2017 12:43:51 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/places.sqlite-shm = Fri Feb 17 2017 13:03:16 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/cert8.db = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/sessionCheckpoints.json = Fri Feb 17 2017 13:03:19 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/startupCache = Fri Feb 17 2017 13:03:15 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/extensions.json = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:19 INFO - PROCESS | 24591 | No birth date. Using mtime. 13:03:19 INFO - PROCESS | 24591 | Using date: /tmp/tmphQcfDe/profile/addons.json = Fri Feb 17 2017 13:03:17 GMT-0800 (PST) 13:03:20 INFO - PROCESS | 24591 | 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" 13:03:20 INFO - PROCESS | 24591 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:03:21 INFO - TEST-INFO | 24591: exit 0 13:03:21 INFO - Browser initialized. 13:03:21 INFO - Running cycle 1/1 for kraken test... 13:03:21 INFO - TEST-INFO | started process 24679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphQcfDe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:03:33 INFO - PROCESS | 24679 | RSS: Main: 147918848 13:03:33 INFO - PROCESS | 24679 | 13:03:36 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34377/tests/kraken/driver.html?testName=audio-beat-detection) 13:03:37 INFO - PROCESS | 24679 | RSS: Main: 202076160 13:03:37 INFO - PROCESS | 24679 | 13:03:39 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34377/tests/kraken/driver.html?testName=audio-dft) 13:03:40 INFO - PROCESS | 24679 | RSS: Main: 181194752 13:03:40 INFO - PROCESS | 24679 | 13:03:43 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34377/tests/kraken/driver.html?testName=audio-fft) 13:03:44 INFO - PROCESS | 24679 | RSS: Main: 263704576 13:03:44 INFO - PROCESS | 24679 | 13:03:46 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34377/tests/kraken/driver.html?testName=audio-oscillator) 13:03:47 INFO - PROCESS | 24679 | RSS: Main: 174985216 13:03:47 INFO - PROCESS | 24679 | 13:03:49 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34377/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:03:49 INFO - PROCESS | 24679 | RSS: Main: 178159616 13:03:49 INFO - PROCESS | 24679 | 13:03:53 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34377/tests/kraken/driver.html?testName=imaging-darkroom) 13:03:53 INFO - PROCESS | 24679 | RSS: Main: 217071616 13:03:53 INFO - PROCESS | 24679 | 13:03:57 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34377/tests/kraken/driver.html?testName=imaging-desaturate) 13:03:57 INFO - PROCESS | 24679 | RSS: Main: 211668992 13:03:57 INFO - PROCESS | 24679 | 13:04:00 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34377/tests/kraken/driver.html?testName=json-parse-financial) 13:04:01 INFO - PROCESS | 24679 | RSS: Main: 214228992 13:04:01 INFO - PROCESS | 24679 | 13:04:02 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34377/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:04:03 INFO - PROCESS | 24679 | RSS: Main: 177315840 13:04:03 INFO - PROCESS | 24679 | 13:04:05 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:04:06 INFO - PROCESS | 24679 | RSS: Main: 183279616 13:04:06 INFO - PROCESS | 24679 | 13:04:08 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:04:08 INFO - PROCESS | 24679 | RSS: Main: 193331200 13:04:08 INFO - PROCESS | 24679 | 13:04:11 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:04:12 INFO - PROCESS | 24679 | RSS: Main: 203100160 13:04:12 INFO - PROCESS | 24679 | 13:04:15 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:04:16 INFO - PROCESS | 24679 | RSS: Main: 198889472 13:04:16 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | Cycle 1(1): loaded http://localhost:34377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34377/tests/kraken/driver.html?testName=ai-astar) 13:04:18 INFO - PROCESS | 24679 | RSS: Main: 250171392 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | __start_tp_report 13:04:18 INFO - PROCESS | 24679 | _x_x_mozilla_page_load 13:04:18 INFO - PROCESS | 24679 | _x_x_mozilla_page_load_details 13:04:18 INFO - PROCESS | 24679 | |i|pagename|runs| 13:04:18 INFO - PROCESS | 24679 | |0;ai-astar;110;117;117;116;115;117;117;115;115;116 13:04:18 INFO - PROCESS | 24679 | |1;audio-beat-detection;147;126;125;167;127;129;127;126;156;126 13:04:18 INFO - PROCESS | 24679 | |2;audio-dft;167;163;163;164;162;163;164;163;163;162 13:04:18 INFO - PROCESS | 24679 | |3;audio-fft;76;76;77;109;78;77;77;77;93;78 13:04:18 INFO - PROCESS | 24679 | |4;audio-oscillator;84;84;112;84;84;84;101;85;85;118 13:04:18 INFO - PROCESS | 24679 | |5;imaging-gaussian-blur;114;114;114;114;113;115;113;113;113;113 13:04:18 INFO - PROCESS | 24679 | |6;imaging-darkroom;148;150;150;150;149;150;149;148;150;150 13:04:18 INFO - PROCESS | 24679 | |7;imaging-desaturate;96;95;96;96;95;96;96;95;96;97 13:04:18 INFO - PROCESS | 24679 | |8;json-parse-financial;63;61;62;61;63;63;62;62;63;62 13:04:18 INFO - PROCESS | 24679 | |9;json-stringify-tinderbox;67;66;70;65;67;66;66;67;67;66 13:04:18 INFO - PROCESS | 24679 | |10;stanford-crypto-aes;89;87;89;89;87;88;88;91;88;87 13:04:18 INFO - PROCESS | 24679 | |11;stanford-crypto-ccm;149;148;136;147;137;139;136;138;176;156 13:04:18 INFO - PROCESS | 24679 | |12;stanford-crypto-pbkdf2;186;191;188;184;189;189;185;184;188;184 13:04:18 INFO - PROCESS | 24679 | |13;stanford-crypto-sha256-iterative;75;70;69;70;69;69;70;70;70;71 13:04:18 INFO - PROCESS | 24679 | __end_tp_report 13:04:18 INFO - PROCESS | 24679 | __start_cc_report 13:04:18 INFO - PROCESS | 24679 | _x_x_mozilla_cycle_collect,2034 13:04:18 INFO - PROCESS | 24679 | __end_cc_report 13:04:18 INFO - PROCESS | 24679 | __startTimestamp1487365458363__endTimestamp 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | ------- Summary: start ------- 13:04:18 INFO - PROCESS | 24679 | Number of tests: 14 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.50 stddev:2.12 (1.8%) stddev-sans-first:0.93 13:04:18 INFO - PROCESS | 24679 | Values: 110.0 117.0 117.0 116.0 115.0 117.0 117.0 115.0 115.0 116.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:128.00 stddev:15.32 (12.0%) stddev-sans-first:15.68 13:04:18 INFO - PROCESS | 24679 | Values: 147.0 126.0 125.0 167.0 127.0 129.0 127.0 126.0 156.0 126.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.00 stddev:1.43 (0.9%) stddev-sans-first:0.71 13:04:18 INFO - PROCESS | 24679 | Values: 167.0 163.0 163.0 164.0 162.0 163.0 164.0 163.0 163.0 162.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#3] audio-fft Cycles:10 Average:81.80 Median:77.00 stddev:10.82 (14.1%) stddev-sans-first:11.27 13:04:18 INFO - PROCESS | 24679 | Values: 76.0 76.0 77.0 109.0 78.0 77.0 77.0 77.0 93.0 78.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:84.00 stddev:13.23 (15.7%) stddev-sans-first:13.70 13:04:18 INFO - PROCESS | 24679 | Values: 84.0 84.0 112.0 84.0 84.0 84.0 101.0 85.0 85.0 118.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 13:04:18 INFO - PROCESS | 24679 | Values: 114.0 114.0 114.0 114.0 113.0 115.0 113.0 113.0 113.0 113.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:150.00 stddev:0.84 (0.6%) stddev-sans-first:0.73 13:04:18 INFO - PROCESS | 24679 | Values: 148.0 150.0 150.0 150.0 149.0 150.0 149.0 148.0 150.0 150.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 13:04:18 INFO - PROCESS | 24679 | Values: 96.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 97.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:62.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 13:04:18 INFO - PROCESS | 24679 | Values: 63.0 61.0 62.0 61.0 63.0 63.0 62.0 62.0 63.0 62.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.41 13:04:18 INFO - PROCESS | 24679 | Values: 67.0 66.0 70.0 65.0 67.0 66.0 66.0 67.0 67.0 66.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:88.50 stddev:1.25 (1.4%) stddev-sans-first:1.30 13:04:18 INFO - PROCESS | 24679 | Values: 89.0 87.0 89.0 89.0 87.0 88.0 88.0 91.0 88.0 87.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:147.50 stddev:12.51 (8.5%) stddev-sans-first:13.22 13:04:18 INFO - PROCESS | 24679 | Values: 149.0 148.0 136.0 147.0 137.0 139.0 136.0 138.0 176.0 156.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:188.00 stddev:2.53 (1.3%) stddev-sans-first:2.67 13:04:18 INFO - PROCESS | 24679 | Values: 186.0 191.0 188.0 184.0 189.0 189.0 185.0 184.0 188.0 184.0 13:04:18 INFO - PROCESS | 24679 | 13:04:18 INFO - PROCESS | 24679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:1.77 (2.5%) stddev-sans-first:0.67 13:04:18 INFO - PROCESS | 24679 | Values: 75.0 70.0 69.0 70.0 69.0 69.0 70.0 70.0 70.0 71.0 13:04:18 INFO - PROCESS | 24679 | -------- Summary: end -------- 13:04:18 INFO - PROCESS | 24679 | 13:04:19 INFO - TEST-INFO | 24679: exit 0 13:04:19 INFO - TEST-OK | kraken | took 64172ms 13:04:19 INFO - SUITE-END | took 1187s 13:04:20 INFO - Completed test suite (00:19:48) 13:04:20 INFO - javascript benchmark 13:04:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6266.034595778889, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.98713006435, 2599.0, 2616.3459135216203, 2626.9080582179627, 2633.9078132265377, 4547.158775626512, 4610.769461526927, 4698.953010469894, 4714.528547145288, 4721.669483136182, 4693.0, 4715.811367545294, 4741.834035808747, 4754.595859351951, 4832.516748325165, 4283.485981682203, 4374.60628543431, 4389.078293558357, 4408.735475871442, 4438.267685831834, 4429.136318417926, 4468.374427580109, 4484.1928452878465, 4487.618552423048, 4510.188166130095, 4277.764722940237, 4294.935575966363, 4304.375865499516, 4356.3683265926265, 4363.956360436387, 4372.956270437287, 4405.977970110161, 4410.669199809995, 4464.196444639963, 4482.260427029536, 4377.912441751179, 4382.956170438287, 4401.713888597231, 4407.537208593084, 4441.511433742286, 4617.838375656869, 4624.167649823031, 4650.372199753024, 4667.113248482778, 4705.1530724469585, 5059.772310246048, 5060.316857224265, 5061.822836200715, 5065.113605119126, 5065.493450654942, 5056.82301119459, 5076.44159142494, 5096.770645320969, 5099.898002039976, 5105.463926287724, 4745.738984355859, 4817.759112044454, 4820.493848145964, 4824.252240902666, 4826.083044221657, 5218.191180367049, 5218.7912483500995, 5219.634625576249, 5221.921671174935, 5260.684358938476, 4820.783064762094, 4871.318015477836, 4932.309476673268, 5000.64995450315, 5010.298558201855, 5018.24726291052, 5030.144875371196, 5074.213496907985, 5075.670081444694, 5081.18701007837, 4937.259411088366, 5051.393832740095, 5054.7472626368535, 5071.163258062378, 5114.48855114493, 4976.0, 4989.351384320015, 5036.0, 5061.670991385548, 5064.088464076428, 4317.417148684887, 4320.632746216606, 4332.610065094157, 4332.978335108304, 4333.783310834446, 3714.832832522543, 3727.7390582659496, 3748.025513366544, 3788.924221515554, 3790.223004284115, 4446.510883802844, 4448.488423831236, 4450.087732014929, 4450.5549445055885, 4500.8649740507835, 5128.640995130305, 5136.974315128476, 5139.511746384088, 5142.640015198975, 5201.973990130101, 4287.7641729705165, 4313.288307429302, 4450.310201918708, 4483.14820184164, 4487.237169681162, 4669.066186762661, 4689.343491911135, 4721.0, 4726.007538416971, 4727.527247275245, 4851.660383773173, 4906.190478571139, 4906.6320025997475, 4910.901781964412, 4941.110600092018, 4938.111139994692, 4990.6506544541535, 5208.453112423293, 5214.165733482625, 5217.34783152106, 4745.311929770233, 4758.738269395078, 4761.880952976203, 4766.833160839354, 4803.471618121934, 5120.385553733576, 5120.974395128001, 5122.948770512247, 5127.53852153308, 5130.7178105204575, 5150.562202212704, 5153.948460515347, 5154.61340399479, 5155.943031678422, 5158.948410515997, 5017.3226614406585, 5018.1469150245275, 5019.272205530251, 5021.799128034838, 5064.797408103635, 4765.9523404765505, 4828.7102773833685, 4856.320115183948, 4870.4155501340065, 4956.207006879026, 4889.706617602955, 4940.580050695731, 4941.0, 4968.652194346506, 4995.525425084611, 5031.597472202288, 5032.572231360229, 5035.899282014412, 5041.672291301201, 5083.034223497523, 4630.467496237976, 4673.696209746492, 4917.606591472745, 4925.088858561177, 4925.901481970269], "value": 4678.421069286028, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.5791340667686, 560.963537370071, 566.2214455124201, 577.1948132355362, 595.8152972578498, 27402.862985685068, 38330.61669383305, 40207.195856082864, 40231.19537609246, 40396.19207615846, 39130.0, 39401.0, 39565.61520346788, 39574.0, 39587.208255834936, 7538.0, 7715.614219289041, 7754.185810489902, 7798.883016754753, 7815.921840781604, 6831.248562658104, 7100.6449677516175, 9536.284778641595, 9856.655017074403, 9878.357906736039, 9712.174465170468, 9731.318807683465, 9746.0, 9760.4631745254, 9767.316287859852, 9737.221022318234, 9797.363171393836, 9797.608095676165, 9803.166730827888, 9804.803903921953, 9498.905010949871, 9575.186109180659, 9575.664851730156, 9576.425414475085, 9576.856347154799, 35828.0, 36307.63692363069, 36321.81839090814, 36450.45324320137, 36563.268734625155, 3506.9824650876835, 3523.894283171509, 3532.7181834009157, 3573.76770509916, 3707.2400157967554, 26884.865575671996, 26924.192274231806, 28346.433071338663, 28425.43149137006, 28750.281242968886, 3169.3344397676515, 3220.8872689455993, 3298.5546951161527, 3316.4527852904243, 3375.6455572164814, 216736.99156101368, 216941.0, 217202.0, 217461.0, 218370.0, 42003.0, 50101.49898501041, 50741.23888141681, 50746.23880641793, 50752.238716419284, 45422.0, 45618.77190614026, 45928.3110753339, 53013.204801928005, 54331.491988901784, 11547.884521154849, 11780.764384712258, 11817.940910295394, 11818.822717659243, 11832.585859494877, 7847.332976696931, 8703.6518539259, 8766.123387661182, 8769.693060742844, 8783.560821958876, 12720.93639531815, 14742.189179595225, 15162.848371516144, 15178.924105379403, 15718.21408929549, 5566.749496272678, 5568.192612071227, 5616.85957851057, 5730.942690573124, 5740.971295143498, 770.9845803084019, 781.1719577248135, 781.8162731758032, 799.476342995339, 810.2262339465796, 26293.28196965073, 26498.735012650013, 26629.0, 26843.731562684512, 26943.86528067347, 901.7565257380602, 909.1862782809349, 910.1080940678172, 913.3287034029983, 931.7484279244566, 13911.30443477822, 14222.715545689236, 14337.498187563386, 14489.420423183166, 14618.926905365617, 319402.0, 321987.0, 322123.0, 322354.0, 322627.8321035057, 46651.76674116608, 46801.765991170505, 46818.5318146821, 46838.0, 46910.765446172554, 1151.8560179977503, 1156.4564654612318, 1174.8237764335418, 1185.140772939612, 1187.2876274235562, 24547.38631534226, 25315.367115822253, 25790.484190316463, 25965.22104336873, 26046.73953260443, 39214.607853922236, 39796.40305395363, 39798.60201398065, 39827.40258896177, 39860.601393985686, 274871.0, 275453.0, 276720.0, 277099.0, 278531.0, 27925.441491169655, 32634.34731305408, 32775.836120819244, 33187.17032074121, 33381.83309083536, 32372.514412283363, 32538.186545336557, 39886.0, 42712.57287427086, 43297.35053974256, 34394.31211375808, 35058.64941350554, 36938.97416697866, 39162.60837391589, 39851.0, 35222.47166292559, 35337.64662353344, 35810.28379432449, 35871.46192807058, 36197.276054478236, 35825.0, 35835.82082089573, 35946.0, 36442.8177859109, 37102.257954840214, 30352.69647303587, 32520.551623988922, 32757.34485310328, 33202.667973319956, 33300.333993320484, 24610.261692149263, 24924.875375623007, 25091.87454062718, 25122.49755004853, 25516.48967020686], "value": 16928.579844095653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.52938121679364, 370.2447008103467, 389.1885418901591, 390.9648131668149, 405.58225028220943, 12383.814242786355, 13247.867521324784, 13455.730885382287, 17501.299948002066, 18470.44588662339, 16423.589410264718, 18351.816481835176, 18652.0, 18768.061596920168, 18790.53023674409, 4955.554000139978, 5194.324737784102, 5204.037253108168, 5209.583233341343, 5210.062188806013, 5038.571721403685, 5128.0, 5135.666181698196, 5182.041322355376, 5216.321878155834, 5140.0, 5144.717040562768, 5146.176611742122, 5158.071547121516, 5164.845054648366, 4993.7503124843615, 5038.042771873369, 5092.28707980883, 5127.5128862758165, 5131.589472842183, 4556.954430455686, 4566.634669226454, 4578.931316030262, 4581.129585378767, 4611.054733779567, 20473.692894606593, 21226.25708100209, 21306.14775408982, 21323.253686121065, 21422.78577214239, 2233.3188377544843, 2272.9659055114184, 2292.86242825431, 2314.6065168921323, 2318.6406107053435, 13262.801057984138, 13385.86614133856, 13506.527271545547, 13627.114237574626, 14339.78490322646, 1685.1742646103444, 1685.6375879186035, 1686.7048266553427, 1687.7890263717036, 1723.1470422140994, 59908.40091599115, 64120.0, 73247.63376183138, 73250.26749732488, 74741.62629186819, 9947.253955953332, 10101.747456313651, 10112.443815590213, 10410.115140213014, 10518.263721539572, 14016.789748153786, 14038.508652197124, 14057.367418466194, 14061.367238474293, 14075.225862577452, 6344.460720838781, 6413.8717225656155, 6481.0602462642655, 6643.302453242437, 6662.800115996528, 5651.886962260732, 5840.883182336414, 5846.239988801429, 5849.94150058494, 5852.736626851802, 6399.680015999182, 6684.665766711645, 6685.532012759158, 6701.056693558813, 6718.193816741925, 1762.9559261018321, 1768.734689796515, 1778.2797966823457, 1781.2251670523365, 1784.2060283174123, 785.615048626169, 797.9760607181794, 799.1409235072321, 804.3645520039104, 810.720301495983, 16976.151192440328, 17663.823361766474, 18165.636687266182, 18261.360852370104, 18512.352067677566, 771.4715419937361, 784.7018133109497, 794.7814351053391, 799.6121880887765, 805.1505661527145, 9378.155965963097, 10161.745956351151, 10166.237532185116, 10257.33327333721, 10406.427646479517, 452263.0, 454433.0, 456258.0, 459310.0, 465368.0, 59579.40420595825, 73467.0, 74891.62554187303, 74924.62537687276, 76498.61750691211, 883.9071897450804, 906.3338446242007, 930.6835675870101, 936.4194199596158, 936.798588303518, 15078.472253471076, 15162.469313573973, 15686.372545098067, 15846.44537441184, 15967.361305547647, 34609.65390346165, 34636.96089117331, 34705.82647086748, 35504.11239719028, 35642.0, 45617.31574026459, 46509.53490465052, 46926.0, 46957.295640564735, 47175.0, 7672.5012874163, 7748.418868584766, 7804.0, 7843.372530197685, 8036.959815200887, 12353.0, 12417.813732794197, 12434.56479023266, 12503.312317822607, 12552.560660376843, 10224.53989570486, 10449.0, 10463.895361046292, 10639.946800265949, 10837.0, 10421.218408619536, 10441.68674939753, 10444.895551044696, 10606.946965265124, 10736.30214036085, 10489.685309440729, 10498.895011050097, 10627.36235825822, 10692.89307106919, 10837.67486975392, 7787.92212077872, 7796.688132474865, 7831.686732530635, 7928.41431713648, 7932.722354717557, 10377.16982641402, 10412.895871041495, 10488.423136727553, 10544.156467482737, 10717.196210284415], "value": 8325.4480109819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1187.180845216801, 1313.0152385710717, 1318.578055022392, 1323.4772264955343, 1327.1572551429842, 6215.689215539222, 6951.826204344888, 7085.397741192005, 7128.215896251415, 7357.632118394085, 7198.388137008361, 7267.454940879429, 7269.3094156055095, 7280.089988751406, 7284.672189751461, 2031.2890488329078, 2061.8556701030934, 2064.4735592423913, 2064.5354795171115, 2064.7006184103298, 1784.3843873863495, 1835.3667984545377, 2066.5350296183387, 2143.239150101707, 2148.205164089289, 2088.2899814063217, 2088.446561661161, 2091.654876945302, 2095.0886364431444, 2097.590969760897, 1998.15078591598, 2005.4384772263716, 2019.8384129269623, 2020.3837829462009, 2028.8579799414047, 2007.9498012549802, 2016.9294074707461, 2069.2343832781967, 2071.461420030803, 2073.6993135995194, 7299.124105107368, 7362.484626076177, 7363.815904602374, 7365.963170184168, 7368.336849683555, 4309.762963037032, 4430.623397011258, 4432.15789000089, 4434.600885920283, 4434.889127771799, 2781.332976752174, 2888.321244507539, 2899.144752298077, 2944.5730369096577, 2946.7053294670577, 1108.905743011845, 1157.4675649201376, 1159.4087015622008, 1164.56852388706, 1182.2197349749124, 9779.706608801747, 9785.412875227437, 9870.111689947867, 9908.554115064839, 9917.801643967152, 3868.4197370394104, 4107.97946010274, 4117.711760176759, 4167.583021772612, 4168.687348448879, 8289.709860154866, 8298.170182981654, 8301.169883011651, 8303.501789892505, 8309.37679674027, 4506.324051392252, 4559.52125026874, 4561.657875659339, 4562.977185114053, 4574.565416285448, 4039.151778126626, 4107.835686572504, 4163.812628431728, 4170.541240463546, 4173.874783756492, 3397.779144355609, 3408.9659103409144, 3574.7318951078773, 3586.8565257389414, 3599.46008098782, 1116.017904244276, 1161.814109742437, 1169.6491052684162, 1170.4791367841399, 1177.9116566257394, 791.1416113516783, 803.2609998801117, 812.0580127052591, 816.8284660221301, 820.274057459141, 4620.214563524209, 4676.883077923079, 4787.61699064074, 4834.0, 4903.901921961541, 524.4336116993637, 529.9602529810203, 533.8745394832212, 537.965032272901, 538.2787065332474, 2977.389635124794, 3006.3686625808386, 3016.6078409807023, 3088.166195127303, 3092.721655051056, 8316.916830831735, 8557.871631925525, 8611.440256383315, 8756.343274254456, 8761.518116503654, 8643.567821608895, 8676.08901065379, 8677.349198810114, 8689.435186712843, 8715.346349023848, 6590.9670451647435, 6781.287964763727, 6844.2129155147795, 6847.349501797321, 6858.725650974005, 4695.389599352063, 4701.788419521198, 4707.576318131385, 4768.451628062817, 4792.0, 7826.843463130819, 7841.686332546634, 7962.203779622223, 8043.396745244013, 8050.758477245692, 7966.123726389977, 8081.070676872, 8166.346692264723, 8198.918010819816, 8202.83594328122, 5671.602987790815, 5972.313183983897, 6251.4061164189325, 6302.432781049728, 6314.242290925118, 7073.363397294268, 7135.571865688075, 7140.285971402818, 7146.106736657918, 7149.0, 6203.31763506023, 6275.3724627536885, 6276.089966954857, 6291.874162516816, 6465.579737317059, 6182.629042257479, 6187.721552530237, 6214.378562143749, 6219.191505104308, 6365.2361716594305, 5506.174073888948, 5585.748641311069, 5588.077967135539, 5593.0, 5596.440355964371, 4252.596003379674, 4287.721298115737, 4315.4605674290715, 4337.7614231217585, 4389.144116897174, 4074.1851629673592, 4208.684348673923, 4221.535631080517, 4246.299360605589, 4267.381229721611], "value": 3838.5932260348022, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.894010207091, 371.90888232383054, 373.18645353130177, 373.5573345585481, 374.923140756145, 4721.12659158056, 4738.502457241987, 4787.186178349681, 4801.782805520301, 4844.1038407894475, 4713.222318317482, 4730.148573256821, 4746.881327966803, 4770.189067858464, 4792.089502994429, 3631.4008188648972, 3725.8449880536987, 3743.28877513273, 3747.1194269346684, 3792.127810603563, 3752.8686495972647, 3760.717946154036, 3772.6793222576116, 3774.1508160663907, 3783.678387337066, 3738.5513738351437, 3750.4374343848367, 3758.68051215647, 3759.154190307186, 3770.2836461072447, 3675.1547144156725, 3755.6432138946757, 3757.4927384803113, 3780.262848744496, 3803.6386543278345, 3288.4409650359257, 3295.653956334598, 3296.0, 3296.626428971137, 3319.6348401675796, 4664.790084446208, 4672.495628278791, 4685.2269375553, 4693.460252071021, 4698.530146985308, 4232.280512312914, 4236.449261596003, 4238.427812245338, 4250.91498170038, 4268.250017492813, 4747.7626118693925, 4919.2129259318335, 4970.577500912426, 4974.228994505821, 5022.372203474566, 3580.4629305604108, 3595.4359853463825, 3604.477350784148, 3617.2946275476293, 3618.9819050904575, 4962.9007419851405, 5069.923951140736, 5134.743262836843, 5239.175401956593, 5311.442298558673, 3600.2979419013564, 3605.531280933462, 3614.981925090358, 3625.3474374612297, 3639.636036396339, 3650.963490365062, 3657.6890964268337, 3669.8439991402624, 3673.081729567608, 3676.191237927652, 3995.4606128172904, 4101.384792281182, 4202.243596152722, 4209.421991307426, 4211.515206028458, 4720.88197795051, 4957.355543779286, 4971.776270067856, 4973.35346404965, 5014.548690617862, 1233.2970206981934, 1235.690168421476, 1244.7759403307311, 1248.4382028087398, 1262.5707259531803, 806.9878951815728, 856.4518708026868, 857.8327226190833, 875.2166810704384, 876.250805561236, 4278.764667943293, 4280.507741609755, 4291.206126866539, 4299.215825432473, 4303.376010478463, 4928.609624332196, 4937.901241975141, 5004.598712360545, 5010.49895010496, 5012.348394708737, 4261.232978063917, 4299.269124248885, 4306.375575541529, 4328.870133895988, 4354.825806967686, 2503.7996960243136, 2621.934451638686, 2629.6975847777367, 2640.6435131257417, 2655.5751079827523, 4676.649251306166, 4697.459792123949, 4701.177293973541, 4721.662776925444, 4725.818545363659, 5042.949570504321, 5084.669496482717, 5088.821891233789, 5096.413912400121, 5110.821121260738, 4558.202314594932, 4832.685875418087, 4870.342503761947, 4879.853604391874, 4886.462489126265, 5150.81090536528, 5164.767585458589, 5171.3535808024, 5190.351206099238, 5208.479152084761, 4888.87334009707, 5101.591872650105, 5105.2342148678, 5123.74381280927, 5135.15269980449, 4642.257238841769, 4732.621390288699, 4831.4685384608365, 4855.067395409729, 4862.811734719319, 3541.3802584547084, 3572.285542891484, 3580.7135429165082, 3599.892003239907, 3690.1697118148204, 5142.305788718625, 5165.457626949266, 5179.472055743489, 5180.736136234148, 5197.454267301879, 4886.169351210232, 5016.698998060128, 5020.673656212334, 5039.168537191323, 5055.443901170795, 4931.0, 4975.676581022222, 4977.117848078505, 4977.153883839828, 4986.0, 4901.803927842989, 4922.310876477224, 4935.75321233944, 4966.205407134841, 4975.7014579123925, 4742.620590352694, 4951.638299467675, 5003.599712023102, 5016.147254966738, 5035.672681275705, 4902.3872015998, 4902.509749025069, 4914.238293064581, 4916.0, 4928.310036594951], "value": 3769.954803926988, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.9728010879566, 681.696644992978, 728.6757392960131, 740.9259074092591, 751.2262369759146, 12327.753444931108, 12879.54921577745, 13153.342332883365, 13158.802617960739, 13168.407421666025, 12735.363231838393, 12797.976161907049, 12849.614511564645, 12855.292958887281, 12940.676483087927, 817.1215942861418, 832.0556168748484, 842.3008902610818, 843.3211264931728, 843.9704610338639, 849.613425891218, 851.3870013590205, 851.5699571716275, 851.7572491839827, 852.0797538658265, 883.8674198870157, 886.8625363068702, 886.8935727712684, 887.024273299372, 887.2458410351214, 871.1941454154913, 876.1370050500252, 876.3427429427929, 880.4453194487464, 880.5861245214771, 651.6774196772628, 653.7090994507446, 653.8757636049136, 654.8428377189488, 655.4756195043993, 13964.860351396457, 14156.150630962175, 14301.856981430157, 14302.356393962296, 14559.78160327596, 13447.731045379136, 13662.108986843667, 13995.30023498821, 14033.929830350886, 14094.295285235698, 10700.357978521313, 10703.732406689816, 10734.53922637629, 11037.668869933916, 11344.546218151265, 3158.7157155855853, 3159.920629000257, 3160.7471402287993, 3170.6036745406823, 3188.2666986593213, 58280.417195827926, 58329.416705833246, 58474.70762646202, 58971.0, 60403.69798150981, 9683.305745567672, 9768.21854251658, 9803.313768036312, 9896.653617123366, 10027.649032283834, 19483.902580487007, 19499.512512187026, 19535.511612209808, 19548.31580894662, 19773.901130494254, 5625.46558076982, 5768.94231057684, 5887.234659494238, 5907.881842363215, 5912.231409916683, 3362.36115138123, 3434.708049815744, 3483.7387195960405, 3498.352804731133, 3558.0, 3038.5745995560637, 3041.4525385430393, 3101.5347697845505, 3109.5646609474693, 3130.4834702274325, 497.7685376300038, 498.2526210683974, 503.9672421292604, 515.4407467897373, 515.6673945305257, 633.3603060908515, 633.9270983836825, 654.4339146638218, 659.4065341192965, 664.1598378051768, 8961.969373521997, 8971.865422018675, 9000.189982901571, 9240.306976976754, 9339.0, 639.3638329861817, 668.5353679193012, 669.2103318084706, 672.7140965089817, 674.5918719174829, 4028.718194002269, 4096.32410652245, 4358.346248062752, 4358.346248062816, 4402.955970440319, 60404.0, 61085.69457152686, 62286.68856655688, 64590.0, 64666.67666661637, 53030.204546931825, 54060.459395405545, 54164.72917635465, 55116.44883551193, 55280.723596382566, 45057.32414013793, 45456.54543454589, 46114.308285375744, 46826.0, 47912.7604361976, 8748.65005399777, 9258.259339252978, 9370.437773733598, 9708.854367184356, 9713.125818676353, 29059.7094029057, 29325.413491729618, 29357.706422936353, 30233.546496802126, 33737.493937590465, 35607.82196089003, 40594.94050594917, 41985.58014419939, 43482.34776478292, 44491.555084448744, 11314.60398886035, 11454.541818327174, 11545.0, 11545.538178472767, 11573.594924177612, 12881.871181288067, 12990.220586764824, 13007.154534955198, 13095.333306674147, 13228.41344108899, 11543.769124617293, 11705.88294117082, 12036.916677499066, 12102.0, 12105.576304829288, 11640.301581905112, 12026.819597705866, 12327.075469339656, 12584.433700483318, 12587.68530786701, 11357.261777984404, 11460.14048946349, 11590.88409115898, 11667.474963626826, 11726.0, 9579.271344953871, 9807.313488055768, 9924.255680824112, 10034.648787292701, 10233.334833236113, 8587.853036740815, 8752.51861147643, 9541.757686943702, 9898.851517227391, 10017.749556261151], "value": 6342.631220424976, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 117.0, 117.0, 116.0, 115.0, 117.0, 117.0, 115.0, 115.0, 116.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 126.0, 125.0, 167.0, 127.0, 129.0, 127.0, 126.0, 156.0, 126.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 163.0, 164.0, 162.0, 163.0, 164.0, 163.0, 163.0, 162.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 109.0, 78.0, 77.0, 77.0, 77.0, 93.0, 78.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 112.0, 84.0, 84.0, 84.0, 101.0, 85.0, 85.0, 118.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 115.0, 113.0, 113.0, 113.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 150.0, 150.0, 149.0, 150.0, 149.0, 148.0, 150.0, 150.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 97.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 61.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 65.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 89.0, 87.0, 88.0, 88.0, 91.0, 88.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 136.0, 147.0, 137.0, 139.0, 136.0, 138.0, 176.0, 156.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 191.0, 188.0, 184.0, 189.0, 189.0, 185.0, 184.0, 188.0, 184.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 69.0, 70.0, 69.0, 69.0, 70.0, 70.0, 70.0, 71.0], "value": 70.3, "unit": "score"}]}]} 13:04:20 INFO - Return code: 0 13:04:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:04:20 ERROR - # TBPL SUCCESS # 13:04:20 INFO - Running post-action listener: _resource_record_post_action 13:04:20 INFO - [mozharness: 2017-02-17 21:04:20.160270Z] Finished run-tests step (success) 13:04:20 INFO - Running post-run listener: _resource_record_post_run 13:04:20 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 0; Write bytes: 368410624; Read time: 0; Write time: 245968 13:04:20 INFO - TinderboxPrint: CPU usage
13.1% 13:04:20 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 13:04:20 INFO - TinderboxPrint: I/O write bytes / time
368,410,624 / 245,968 13:04:20 INFO - TinderboxPrint: CPU idle
8,348.8 (86.9%) 13:04:20 INFO - TinderboxPrint: CPU user
1,217.0 (12.7%) 13:04:20 INFO - TinderboxPrint: Swap in / out
0 / 0 13:04:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 111947776; Read time: 0; Write time: 80940 13:04:20 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 255987712; Read time: 0; Write time: 165004 13:04:20 INFO - Running post-run listener: _upload_blobber_files 13:04:20 INFO - Blob upload gear active. 13:04:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:04:20 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 13:04:20 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:04:20 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:04:21 INFO - (blobuploader) - INFO - Open directory for files ... 13:04:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:04:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:04:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:23 INFO - (blobuploader) - INFO - Done attempting. 13:04:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:04:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:04:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:27 INFO - (blobuploader) - INFO - Done attempting. 13:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:04:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:29 INFO - (blobuploader) - INFO - Done attempting. 13:04:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:04:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:04:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:31 INFO - (blobuploader) - INFO - Done attempting. 13:04:31 INFO - (blobuploader) - INFO - Iteration through files over. 13:04:31 INFO - Return code: 0 13:04:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:04:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:04:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0621228ba9ed3f1cf79511abe9f6f58444bf611bdbe7e99400af215bb18ce56698fbc2cb2cf933f4284ca1a895b8586f40c8ad4a7faf290ba3f1ef45f74f5bc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85b01caf7d9f9d207a152b49894750dbf486a4941e871a4160ea0e59a5701dba185eb8c2122ec515b3a41065d02aac4de09565d64ca9ba58830d76b07336b590", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24440a95d68765eb848e89c087d996acef9fd1ba17cf824ea85a692ed46c1f791acc6d382e41ccbbca9b5cdf93e8554f47bf7b173b60d515ea7a51f6ca320e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/666f392af2f636ed58ae3d225668efe6e9403f2cf92049da596b416841bedbabcf59daea6c8802b41222b1c508a5ff065795ba0ddf593ecaf404663bb3a1db0f"} 13:04:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:04:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:04:31 INFO - Contents: 13:04:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0621228ba9ed3f1cf79511abe9f6f58444bf611bdbe7e99400af215bb18ce56698fbc2cb2cf933f4284ca1a895b8586f40c8ad4a7faf290ba3f1ef45f74f5bc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85b01caf7d9f9d207a152b49894750dbf486a4941e871a4160ea0e59a5701dba185eb8c2122ec515b3a41065d02aac4de09565d64ca9ba58830d76b07336b590", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24440a95d68765eb848e89c087d996acef9fd1ba17cf824ea85a692ed46c1f791acc6d382e41ccbbca9b5cdf93e8554f47bf7b173b60d515ea7a51f6ca320e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/666f392af2f636ed58ae3d225668efe6e9403f2cf92049da596b416841bedbabcf59daea6c8802b41222b1c508a5ff065795ba0ddf593ecaf404663bb3a1db0f"} 13:04:31 INFO - Running post-run listener: copy_logs_to_upload_dir 13:04:31 INFO - Copying logs to upload dir... 13:04:31 INFO - mkdir: /builds/slave/test/build/upload/logs 13:04:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.242632 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-02-17 13:04:31.614910) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 13:04:31.618790) ========= 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=31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379 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-Non-PGO/sha512/0621228ba9ed3f1cf79511abe9f6f58444bf611bdbe7e99400af215bb18ce56698fbc2cb2cf933f4284ca1a895b8586f40c8ad4a7faf290ba3f1ef45f74f5bc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85b01caf7d9f9d207a152b49894750dbf486a4941e871a4160ea0e59a5701dba185eb8c2122ec515b3a41065d02aac4de09565d64ca9ba58830d76b07336b590", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24440a95d68765eb848e89c087d996acef9fd1ba17cf824ea85a692ed46c1f791acc6d382e41ccbbca9b5cdf93e8554f47bf7b173b60d515ea7a51f6ca320e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/666f392af2f636ed58ae3d225668efe6e9403f2cf92049da596b416841bedbabcf59daea6c8802b41222b1c508a5ff065795ba0ddf593ecaf404663bb3a1db0f"} build_url:https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011343 build_url: 'https://queue.taskcluster.net/v1/task/U53J_7G2RWCH-wSTVWq2Qg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0621228ba9ed3f1cf79511abe9f6f58444bf611bdbe7e99400af215bb18ce56698fbc2cb2cf933f4284ca1a895b8586f40c8ad4a7faf290ba3f1ef45f74f5bc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85b01caf7d9f9d207a152b49894750dbf486a4941e871a4160ea0e59a5701dba185eb8c2122ec515b3a41065d02aac4de09565d64ca9ba58830d76b07336b590", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24440a95d68765eb848e89c087d996acef9fd1ba17cf824ea85a692ed46c1f791acc6d382e41ccbbca9b5cdf93e8554f47bf7b173b60d515ea7a51f6ca320e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/666f392af2f636ed58ae3d225668efe6e9403f2cf92049da596b416841bedbabcf59daea6c8802b41222b1c508a5ff065795ba0ddf593ecaf404663bb3a1db0f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 13:04:31.643545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 13:04:31.643851) ========= 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=31acecf42b6a79ec49471d4a000001bb-1487364197.602892-886002379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004407 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 13:04:31.727313) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 13:04:31.727634) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 13:04:31.727924) ========= ========= Total master_lag: 0.18 =========