builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-064 starttime: 1484059737.45 results: success (0) revision: 347892e394570ca7f3f1fe954d813ac2c7a32288 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.451644) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.452110) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.452384) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.467518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.467863) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-10 06:48:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2017-01-10 06:48:58 (14.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123208 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.605228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.605504) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015194 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.654425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:57.654719) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 347892e394570ca7f3f1fe954d813ac2c7a32288 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev 347892e394570ca7f3f1fe954d813ac2c7a32288 --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=a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-10 06:48:58,157 truncating revision to first 12 chars 2017-01-10 06:48:58,157 Setting DEBUG logging. 2017-01-10 06:48:58,157 attempt 1/10 2017-01-10 06:48:58,157 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/347892e39457?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 06:48:58,490 unpacking tar archive at: graphics-347892e39457/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647770 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:58.317185) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:58.317787) ========= script_repo_revision: 347892e394570ca7f3f1fe954d813ac2c7a32288 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:58.318416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:58.318808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 06:48:58.329979) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-10 06:48:58.330597) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:48:58 INFO - MultiFileLogger online at 20170110 06:48:58 in /builds/slave/test 06:48:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 06:48:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:48:58 INFO - {'append_to_log': False, 06:48:58 INFO - 'base_work_dir': '/builds/slave/test', 06:48:58 INFO - 'blob_upload_branch': 'Graphics', 06:48:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:48:58 INFO - 'branch': 'Graphics', 06:48:58 INFO - 'buildbot_json_path': 'buildprops.json', 06:48:58 INFO - 'config_files': ('talos/linux_config.py',), 06:48:58 INFO - 'default_actions': ('clobber', 06:48:58 INFO - 'read-buildbot-config', 06:48:58 INFO - 'download-and-extract', 06:48:58 INFO - 'populate-webroot', 06:48:58 INFO - 'create-virtualenv', 06:48:58 INFO - 'install', 06:48:58 INFO - 'run-tests'), 06:48:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:48:58 INFO - 'download_minidump_stackwalk': True, 06:48:58 INFO - 'download_symbols': 'ondemand', 06:48:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:48:58 INFO - 'tooltool.py': '/tools/tooltool.py', 06:48:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:48:58 INFO - '/tools/misc-python/virtualenv.py')}, 06:48:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:48:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:48:58 INFO - 'installer_path': 'installer.exe', 06:48:58 INFO - 'log_level': 'info', 06:48:58 INFO - 'log_name': 'talos', 06:48:58 INFO - 'log_to_console': True, 06:48:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:48:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:48:58 INFO - 'opt_config_files': (), 06:48:58 INFO - 'pip_index': False, 06:48:58 INFO - 'sps_profile': False, 06:48:58 INFO - 'sps_profile_interval': 0, 06:48:58 INFO - 'suite': 'dromaeojs', 06:48:58 INFO - 'system_bits': '32', 06:48:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:48:58 INFO - 'title': 'talos-linux64-ix-064', 06:48:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:48:58 INFO - 'use_talos_json': True, 06:48:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:48:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:48:58 INFO - 'work_dir': 'build'} 06:48:58 INFO - [mozharness: 2017-01-10 14:48:58.931954Z] Running clobber step. 06:48:58 INFO - Running pre-action listener: _resource_record_pre_action 06:48:58 INFO - Running main action method: clobber 06:48:58 INFO - rmtree: /builds/slave/test/build 06:48:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:48:59 INFO - Running post-action listener: _resource_record_post_action 06:48:59 INFO - [mozharness: 2017-01-10 14:48:59.388837Z] Finished clobber step (success) 06:48:59 INFO - [mozharness: 2017-01-10 14:48:59.388938Z] Running read-buildbot-config step. 06:48:59 INFO - Running pre-action listener: _resource_record_pre_action 06:48:59 INFO - Running main action method: read_buildbot_config 06:48:59 INFO - Using buildbot properties: 06:48:59 INFO - { 06:48:59 INFO - "project": "", 06:48:59 INFO - "product": "firefox", 06:48:59 INFO - "who": "kgupta@mozilla.com", 06:48:59 INFO - "installer_path": "public/build/target.tar.bz2", 06:48:59 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 06:48:59 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 06:48:59 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 06:48:59 INFO - "stage_platform": "linux64", 06:48:59 INFO - "basedir": "/builds/slave/test", 06:48:59 INFO - "buildnumber": 50, 06:48:59 INFO - "platform": "ubuntu64_hw", 06:48:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:48:59 INFO - "slavebuilddir": "test", 06:48:59 INFO - "taskId": "cbFOpi5GQJ2vyn4KL2wToA", 06:48:59 INFO - "branch": "graphics", 06:48:59 INFO - "script_repo_revision": "default", 06:48:59 INFO - "revision": "347892e394570ca7f3f1fe954d813ac2c7a32288", 06:48:59 INFO - "slavename": "talos-linux64-ix-064", 06:48:59 INFO - "repo_path": "projects/graphics" 06:48:59 INFO - } 06:48:59 INFO - Finding installer, test and symbols from parent task. 06:48:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbFOpi5GQJ2vyn4KL2wToA'}, attempt #1 06:48:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbFOpi5GQJ2vyn4KL2wToA'}, attempt #1 06:49:00 INFO - Task dependencies: Pdfufw9bSc-RidALSAjUBQ 06:49:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ'}, attempt #1 06:49:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2 06:49:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/test_packages.json 06:49:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.crashreporter-symbols.zip 06:49:00 INFO - Running post-action listener: _resource_record_post_action 06:49:00 INFO - [mozharness: 2017-01-10 14:49:00.774410Z] Finished read-buildbot-config step (success) 06:49:00 INFO - [mozharness: 2017-01-10 14:49:00.774628Z] Running download-and-extract step. 06:49:00 INFO - Running pre-action listener: _resource_record_pre_action 06:49:00 INFO - Running main action method: download_and_extract 06:49:00 INFO - mkdir: /builds/slave/test/build/tests 06:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:00 INFO - trying https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.test_packages.json 06:49:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:49:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:49:01 INFO - Downloaded 896 bytes. 06:49:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:49:01 INFO - Using the following test package requirements: 06:49:01 INFO - {u'common': [u'target.common.tests.zip'], 06:49:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:49:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:49:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:49:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:49:01 INFO - u'mozbase': [u'target.common.tests.zip'], 06:49:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:49:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:49:01 INFO - u'web-platform': [u'target.common.tests.zip', 06:49:01 INFO - u'target.web-platform.tests.zip'], 06:49:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:49:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:49:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.common.tests.zip 06:49:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:49:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.common.tests.zip into memory 06:49:02 INFO - Expected file size: 20256464 06:49:02 INFO - Obtained file size: 20256464 06:49:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:49:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.common.tests.zip 06:49:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:49:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.common.tests.zip into memory 06:49:06 INFO - Expected file size: 20256464 06:49:06 INFO - Obtained file size: 20256464 06:49:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.talos.tests.zip 06:49:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:49:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.talos.tests.zip into memory 06:49:12 INFO - Expected file size: 14403529 06:49:12 INFO - Obtained file size: 14403529 06:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:12 INFO - trying https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2 06:49:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:49:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:49:13 INFO - Downloaded 56007213 bytes. 06:49:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2 06:49:13 INFO - mkdir: /builds/slave/test/properties 06:49:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:49:13 INFO - Writing to file /builds/slave/test/properties/build_url 06:49:13 INFO - Contents: 06:49:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2 06:49:13 INFO - Running post-action listener: _resource_record_post_action 06:49:13 INFO - Running post-action listener: set_extra_try_arguments 06:49:13 INFO - [mozharness: 2017-01-10 14:49:13.434446Z] Finished download-and-extract step (success) 06:49:13 INFO - [mozharness: 2017-01-10 14:49:13.434543Z] Running populate-webroot step. 06:49:13 INFO - Running pre-action listener: _resource_record_pre_action 06:49:13 INFO - Running main action method: populate_webroot 06:49:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:49:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:49:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:49:13 INFO - '--tppagecycles', 06:49:13 INFO - '7'], 06:49:13 INFO - 'tests': ['tsvgm']}}, 06:49:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:49:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:49:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:49:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:49:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:49:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:49:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['damp', 'tps']}, 06:49:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'tests': ['damp', 'tps']}, 06:49:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['dromaeo_dom']}, 06:49:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:49:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['basic_compositor_video']}, 06:49:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:49:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['a11yr', 06:49:13 INFO - 'ts_paint', 06:49:13 INFO - 'tpaint', 06:49:13 INFO - 'sessionrestore', 06:49:13 INFO - 'sessionrestore_no_auto_restore', 06:49:13 INFO - 'tabpaint']}, 06:49:13 INFO - 'other-e10s': {'tests': ['a11yr', 06:49:13 INFO - 'ts_paint', 06:49:13 INFO - 'tpaint', 06:49:13 INFO - 'sessionrestore', 06:49:13 INFO - 'sessionrestore_no_auto_restore', 06:49:13 INFO - 'tabpaint']}, 06:49:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:49:13 INFO - 'ts_paint', 06:49:13 INFO - 'tpaint', 06:49:13 INFO - 'sessionrestore', 06:49:13 INFO - 'sessionrestore_no_auto_restore', 06:49:13 INFO - 'tabpaint']}, 06:49:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:49:13 INFO - 'ts_paint', 06:49:13 INFO - 'tpaint', 06:49:13 INFO - 'sessionrestore', 06:49:13 INFO - 'sessionrestore_no_auto_restore', 06:49:13 INFO - 'tabpaint']}, 06:49:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['a11yr', 06:49:13 INFO - 'ts_paint', 06:49:13 INFO - 'tpaint', 06:49:13 INFO - 'sessionrestore', 06:49:13 INFO - 'sessionrestore_no_auto_restore', 06:49:13 INFO - 'tabpaint']}, 06:49:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['a11yr', 06:49:13 INFO - 'ts_paint', 06:49:13 INFO - 'tpaint', 06:49:13 INFO - 'sessionrestore', 06:49:13 INFO - 'sessionrestore_no_auto_restore', 06:49:13 INFO - 'tabpaint']}, 06:49:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['tsvgx', 06:49:13 INFO - 'tsvgr_opacity', 06:49:13 INFO - 'tart', 06:49:13 INFO - 'tscrollx', 06:49:13 INFO - 'cart', 06:49:13 INFO - 'tsvg_static']}, 06:49:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:49:13 INFO - 'tsvgr_opacity', 06:49:13 INFO - 'tart', 06:49:13 INFO - 'tscrollx', 06:49:13 INFO - 'cart', 06:49:13 INFO - 'tsvg_static']}, 06:49:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'talos_options': ['--disable-e10s'], 06:49:13 INFO - 'tests': ['tp5o']}, 06:49:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:49:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'talos_options': ['--disable-e10s', 06:49:13 INFO - '--xperf_path', 06:49:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:49:13 INFO - 'tests': ['tp5n']}, 06:49:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:49:13 INFO - 'talos_options': ['--xperf_path', 06:49:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:49:13 INFO - 'tests': ['tp5n']}}, 06:49:13 INFO - 'talos.zip': {'path': '', 06:49:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:49:13 INFO - Running post-action listener: _resource_record_post_action 06:49:13 INFO - [mozharness: 2017-01-10 14:49:13.447625Z] Finished populate-webroot step (success) 06:49:13 INFO - [mozharness: 2017-01-10 14:49:13.447719Z] Running create-virtualenv step. 06:49:13 INFO - Running pre-action listener: _resource_record_pre_action 06:49:13 INFO - Running main action method: create_virtualenv 06:49:13 INFO - Creating virtualenv /builds/slave/test/build/venv 06:49:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:49:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:49:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:49:13 INFO - Using real prefix '/usr' 06:49:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:49:14 INFO - Installing distribute.............................................................................................................................................................................................done. 06:49:16 INFO - Installing pip.................done. 06:49:16 INFO - Return code: 0 06:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:49:16 INFO - Reading from file tmpfile_stdout 06:49:16 INFO - Output received: 06:49:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:49:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:16 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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:49:16 INFO - Using env: {'DISPLAY': ':0', 06:49:16 INFO - 'HOME': '/home/cltbld', 06:49:16 INFO - 'LANG': 'en_US.UTF-8', 06:49:16 INFO - 'LANGUAGE': 'en_US:en', 06:49:16 INFO - 'LOGNAME': 'cltbld', 06:49:16 INFO - 'MAIL': '/var/mail/cltbld', 06:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:16 INFO - 'MOZ_NO_REMOTE': '1', 06:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:16 INFO - 'NO_EM_RESTART': '1', 06:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:16 INFO - 'PWD': '/builds/slave/test', 06:49:16 INFO - 'SHELL': '/bin/bash', 06:49:16 INFO - 'SHLVL': '1', 06:49:16 INFO - 'TERM': 'linux', 06:49:16 INFO - 'TMOUT': '86400', 06:49:16 INFO - 'USER': 'cltbld', 06:49:16 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:16 INFO - '_': '/tools/buildbot/bin/python'} 06:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:49:16 INFO - Cleaning up... 06:49:16 INFO - Return code: 0 06:49:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:16 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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:49:16 INFO - Using env: {'DISPLAY': ':0', 06:49:16 INFO - 'HOME': '/home/cltbld', 06:49:16 INFO - 'LANG': 'en_US.UTF-8', 06:49:16 INFO - 'LANGUAGE': 'en_US:en', 06:49:16 INFO - 'LOGNAME': 'cltbld', 06:49:16 INFO - 'MAIL': '/var/mail/cltbld', 06:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:16 INFO - 'MOZ_NO_REMOTE': '1', 06:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:16 INFO - 'NO_EM_RESTART': '1', 06:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:16 INFO - 'PWD': '/builds/slave/test', 06:49:16 INFO - 'SHELL': '/bin/bash', 06:49:16 INFO - 'SHLVL': '1', 06:49:16 INFO - 'TERM': 'linux', 06:49:16 INFO - 'TMOUT': '86400', 06:49:16 INFO - 'USER': 'cltbld', 06:49:16 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:16 INFO - '_': '/tools/buildbot/bin/python'} 06:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:16 INFO - Downloading/unpacking psutil>=3.1.1 06:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:49:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:49:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:49:18 INFO - Installing collected packages: psutil 06:49:18 INFO - Running setup.py install for psutil 06:49:18 INFO - building 'psutil._psutil_linux' extension 06:49:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:49:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:49:19 INFO - building 'psutil._psutil_posix' extension 06:49:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:49:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:49:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:49:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:49:19 INFO - Successfully installed psutil 06:49:19 INFO - Cleaning up... 06:49:19 INFO - Return code: 0 06:49:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:49:19 INFO - Using env: {'DISPLAY': ':0', 06:49:19 INFO - 'HOME': '/home/cltbld', 06:49:19 INFO - 'LANG': 'en_US.UTF-8', 06:49:19 INFO - 'LANGUAGE': 'en_US:en', 06:49:19 INFO - 'LOGNAME': 'cltbld', 06:49:19 INFO - 'MAIL': '/var/mail/cltbld', 06:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:19 INFO - 'MOZ_NO_REMOTE': '1', 06:49:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:19 INFO - 'NO_EM_RESTART': '1', 06:49:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:19 INFO - 'PWD': '/builds/slave/test', 06:49:19 INFO - 'SHELL': '/bin/bash', 06:49:19 INFO - 'SHLVL': '1', 06:49:19 INFO - 'TERM': 'linux', 06:49:19 INFO - 'TMOUT': '86400', 06:49:19 INFO - 'USER': 'cltbld', 06:49:19 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:19 INFO - '_': '/tools/buildbot/bin/python'} 06:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:49:21 INFO - Installing collected packages: mozsystemmonitor 06:49:21 INFO - Running setup.py install for mozsystemmonitor 06:49:21 INFO - Successfully installed mozsystemmonitor 06:49:21 INFO - Cleaning up... 06:49:21 INFO - Return code: 0 06:49:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:49:21 INFO - Using env: {'DISPLAY': ':0', 06:49:21 INFO - 'HOME': '/home/cltbld', 06:49:21 INFO - 'LANG': 'en_US.UTF-8', 06:49:21 INFO - 'LANGUAGE': 'en_US:en', 06:49:21 INFO - 'LOGNAME': 'cltbld', 06:49:21 INFO - 'MAIL': '/var/mail/cltbld', 06:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:21 INFO - 'MOZ_NO_REMOTE': '1', 06:49:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:21 INFO - 'NO_EM_RESTART': '1', 06:49:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:21 INFO - 'PWD': '/builds/slave/test', 06:49:21 INFO - 'SHELL': '/bin/bash', 06:49:21 INFO - 'SHLVL': '1', 06:49:21 INFO - 'TERM': 'linux', 06:49:21 INFO - 'TMOUT': '86400', 06:49:21 INFO - 'USER': 'cltbld', 06:49:21 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:21 INFO - '_': '/tools/buildbot/bin/python'} 06:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:21 INFO - Downloading/unpacking jsonschema==2.5.1 06:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:49:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:24 INFO - Downloading functools32-3.2.3-2.tar.gz 06:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:49:24 INFO - warning: no files found matching '*.txt' 06:49:24 INFO - no previously-included directories found matching 'build' 06:49:24 INFO - no previously-included directories found matching 'dist' 06:49:24 INFO - no previously-included directories found matching '.git*' 06:49:24 INFO - Installing collected packages: jsonschema, functools32 06:49:24 INFO - Running setup.py install for functools32 06:49:24 INFO - warning: no files found matching '*.txt' 06:49:24 INFO - no previously-included directories found matching 'build' 06:49:24 INFO - no previously-included directories found matching 'dist' 06:49:24 INFO - no previously-included directories found matching '.git*' 06:49:24 INFO - Successfully installed jsonschema functools32 06:49:24 INFO - Cleaning up... 06:49:24 INFO - Return code: 0 06:49:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:49:24 INFO - Using env: {'DISPLAY': ':0', 06:49:24 INFO - 'HOME': '/home/cltbld', 06:49:24 INFO - 'LANG': 'en_US.UTF-8', 06:49:24 INFO - 'LANGUAGE': 'en_US:en', 06:49:24 INFO - 'LOGNAME': 'cltbld', 06:49:24 INFO - 'MAIL': '/var/mail/cltbld', 06:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:24 INFO - 'MOZ_NO_REMOTE': '1', 06:49:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:24 INFO - 'NO_EM_RESTART': '1', 06:49:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:24 INFO - 'PWD': '/builds/slave/test', 06:49:24 INFO - 'SHELL': '/bin/bash', 06:49:24 INFO - 'SHLVL': '1', 06:49:24 INFO - 'TERM': 'linux', 06:49:24 INFO - 'TMOUT': '86400', 06:49:24 INFO - 'USER': 'cltbld', 06:49:24 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:24 INFO - '_': '/tools/buildbot/bin/python'} 06:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:49:24 INFO - Cleaning up... 06:49:24 INFO - Return code: 0 06:49:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:49:24 INFO - Using env: {'DISPLAY': ':0', 06:49:24 INFO - 'HOME': '/home/cltbld', 06:49:24 INFO - 'LANG': 'en_US.UTF-8', 06:49:24 INFO - 'LANGUAGE': 'en_US:en', 06:49:24 INFO - 'LOGNAME': 'cltbld', 06:49:24 INFO - 'MAIL': '/var/mail/cltbld', 06:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:24 INFO - 'MOZ_NO_REMOTE': '1', 06:49:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:24 INFO - 'NO_EM_RESTART': '1', 06:49:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:24 INFO - 'PWD': '/builds/slave/test', 06:49:24 INFO - 'SHELL': '/bin/bash', 06:49:24 INFO - 'SHLVL': '1', 06:49:24 INFO - 'TERM': 'linux', 06:49:24 INFO - 'TMOUT': '86400', 06:49:24 INFO - 'USER': 'cltbld', 06:49:24 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:24 INFO - '_': '/tools/buildbot/bin/python'} 06:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:24 INFO - Downloading/unpacking blobuploader==1.2.4 06:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:26 INFO - Downloading blobuploader-1.2.4.tar.gz 06:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:49:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:49:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:27 INFO - Downloading docopt-0.6.1.tar.gz 06:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:49:27 INFO - Installing collected packages: blobuploader, requests, docopt 06:49:27 INFO - Running setup.py install for blobuploader 06:49:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:49:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:49:27 INFO - Running setup.py install for requests 06:49:28 INFO - Running setup.py install for docopt 06:49:28 INFO - Successfully installed blobuploader requests docopt 06:49:28 INFO - Cleaning up... 06:49:28 INFO - Return code: 0 06:49:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:28 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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:49:28 INFO - Using env: {'DISPLAY': ':0', 06:49:28 INFO - 'HOME': '/home/cltbld', 06:49:28 INFO - 'LANG': 'en_US.UTF-8', 06:49:28 INFO - 'LANGUAGE': 'en_US:en', 06:49:28 INFO - 'LOGNAME': 'cltbld', 06:49:28 INFO - 'MAIL': '/var/mail/cltbld', 06:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:28 INFO - 'MOZ_NO_REMOTE': '1', 06:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:28 INFO - 'NO_EM_RESTART': '1', 06:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:28 INFO - 'PWD': '/builds/slave/test', 06:49:28 INFO - 'SHELL': '/bin/bash', 06:49:28 INFO - 'SHLVL': '1', 06:49:28 INFO - 'TERM': 'linux', 06:49:28 INFO - 'TMOUT': '86400', 06:49:28 INFO - 'USER': 'cltbld', 06:49:28 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:28 INFO - '_': '/tools/buildbot/bin/python'} 06:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:49:28 INFO - Running setup.py (path:/tmp/pip-mB_HTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:49:28 INFO - Running setup.py (path:/tmp/pip-B2WPZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:49:28 INFO - Running setup.py (path:/tmp/pip-JmKVvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:49:28 INFO - Running setup.py (path:/tmp/pip-CfU4A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:49:28 INFO - Running setup.py (path:/tmp/pip-FwnX2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:49:28 INFO - Running setup.py (path:/tmp/pip-DQOaB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:49:28 INFO - Running setup.py (path:/tmp/pip-XVRBOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:49:29 INFO - Running setup.py (path:/tmp/pip-lEiCYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:49:29 INFO - Running setup.py (path:/tmp/pip-DVCb0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:49:29 INFO - Running setup.py (path:/tmp/pip-rgOR3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:49:29 INFO - Running setup.py (path:/tmp/pip-gayLTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:49:29 INFO - Running setup.py (path:/tmp/pip-814OvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:49:29 INFO - Running setup.py (path:/tmp/pip-IFgpIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:49:29 INFO - Running setup.py (path:/tmp/pip-GkCwWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:29 INFO - Running setup.py (path:/tmp/pip-IEBoT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:49:29 INFO - Running setup.py (path:/tmp/pip-bZyQXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:49:29 INFO - Running setup.py (path:/tmp/pip-dtry7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:49:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:49:29 INFO - Running setup.py install for manifestparser 06:49:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:49:29 INFO - Running setup.py install for mozcrash 06:49:29 INFO - Running setup.py install for mozdebug 06:49:30 INFO - Running setup.py install for mozdevice 06:49:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Running setup.py install for mozfile 06:49:30 INFO - Running setup.py install for mozhttpd 06:49:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Running setup.py install for mozinfo 06:49:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Running setup.py install for mozInstall 06:49:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Running setup.py install for mozleak 06:49:30 INFO - Running setup.py install for mozlog 06:49:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Running setup.py install for moznetwork 06:49:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:49:30 INFO - Running setup.py install for mozprocess 06:49:30 INFO - Running setup.py install for mozprofile 06:49:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:49:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:49:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:49:31 INFO - Running setup.py install for mozrunner 06:49:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:49:31 INFO - Running setup.py install for mozscreenshot 06:49:31 INFO - Running setup.py install for moztest 06:49:31 INFO - Running setup.py install for mozversion 06:49:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:49:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:49:31 INFO - Cleaning up... 06:49:31 INFO - Return code: 0 06:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:49: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')]} 06:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49: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')]} 06:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:31 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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:49:31 INFO - Using env: {'DISPLAY': ':0', 06:49:31 INFO - 'HOME': '/home/cltbld', 06:49:31 INFO - 'LANG': 'en_US.UTF-8', 06:49:31 INFO - 'LANGUAGE': 'en_US:en', 06:49:31 INFO - 'LOGNAME': 'cltbld', 06:49:31 INFO - 'MAIL': '/var/mail/cltbld', 06:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:31 INFO - 'MOZ_NO_REMOTE': '1', 06:49:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:31 INFO - 'NO_EM_RESTART': '1', 06:49:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:31 INFO - 'PWD': '/builds/slave/test', 06:49:31 INFO - 'SHELL': '/bin/bash', 06:49:31 INFO - 'SHLVL': '1', 06:49:31 INFO - 'TERM': 'linux', 06:49:31 INFO - 'TMOUT': '86400', 06:49:31 INFO - 'USER': 'cltbld', 06:49:31 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:31 INFO - '_': '/tools/buildbot/bin/python'} 06:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:49:31 INFO - Running setup.py (path:/tmp/pip-fg57UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:49:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:49:31 INFO - Running setup.py (path:/tmp/pip-RrgEPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:49:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:49:31 INFO - Running setup.py (path:/tmp/pip-h6iMPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:49:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:49:31 INFO - Running setup.py (path:/tmp/pip-TMEd6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:49:32 INFO - Running setup.py (path:/tmp/pip-YrSdde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:49:32 INFO - Running setup.py (path:/tmp/pip-rh2lxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:49:32 INFO - Running setup.py (path:/tmp/pip-e7Clmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:49:32 INFO - Running setup.py (path:/tmp/pip-AUIGHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:49:32 INFO - Running setup.py (path:/tmp/pip-nzYxMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:49:32 INFO - Running setup.py (path:/tmp/pip-_z5lGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:49:32 INFO - Running setup.py (path:/tmp/pip-LHFCvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:49:32 INFO - Running setup.py (path:/tmp/pip-Oz5g2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:49:32 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)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:49:32 INFO - Running setup.py (path:/tmp/pip-KudfPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:49:32 INFO - Running setup.py (path:/tmp/pip-AUqcCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:32 INFO - Running setup.py (path:/tmp/pip-Ss4P9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:49:32 INFO - Running setup.py (path:/tmp/pip-e_QLOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:49:32 INFO - Running setup.py (path:/tmp/pip-cMyQSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:49:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:34 INFO - Downloading blessings-1.6.tar.gz 06:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:49:35 INFO - Installing collected packages: blessings 06:49:35 INFO - Running setup.py install for blessings 06:49:35 INFO - Successfully installed blessings 06:49:35 INFO - Cleaning up... 06:49:35 INFO - Return code: 0 06:49:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:49:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:49:35 INFO - Reading from file tmpfile_stdout 06:49:35 INFO - Current package versions: 06:49:35 INFO - argparse == 1.2.1 06:49:35 INFO - blessings == 1.6 06:49:35 INFO - blobuploader == 1.2.4 06:49:35 INFO - docopt == 0.6.1 06:49:35 INFO - functools32 == 3.2.3-2 06:49:35 INFO - jsonschema == 2.5.1 06:49:35 INFO - manifestparser == 1.1 06:49:35 INFO - mozInstall == 1.12 06:49:35 INFO - mozcrash == 1.0 06:49:35 INFO - mozdebug == 0.1 06:49:35 INFO - mozdevice == 0.49 06:49:35 INFO - mozfile == 1.2 06:49:35 INFO - mozhttpd == 0.7 06:49:35 INFO - mozinfo == 0.9 06:49:35 INFO - mozleak == 0.1 06:49:35 INFO - mozlog == 3.4 06:49:35 INFO - moznetwork == 0.27 06:49:35 INFO - mozprocess == 0.24 06:49:35 INFO - mozprofile == 0.28 06:49:35 INFO - mozrunner == 6.13 06:49:35 INFO - mozscreenshot == 0.1 06:49:35 INFO - mozsystemmonitor == 0.3 06:49:35 INFO - moztest == 0.8 06:49:35 INFO - mozversion == 1.4 06:49:35 INFO - psutil == 3.1.1 06:49:35 INFO - requests == 1.2.3 06:49:35 INFO - wsgiref == 0.1.2 06:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:35 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 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:49:35 INFO - Using env: {'DISPLAY': ':0', 06:49:35 INFO - 'HOME': '/home/cltbld', 06:49:35 INFO - 'LANG': 'en_US.UTF-8', 06:49:35 INFO - 'LANGUAGE': 'en_US:en', 06:49:35 INFO - 'LOGNAME': 'cltbld', 06:49:35 INFO - 'MAIL': '/var/mail/cltbld', 06:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:35 INFO - 'MOZ_NO_REMOTE': '1', 06:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:35 INFO - 'NO_EM_RESTART': '1', 06:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:35 INFO - 'PWD': '/builds/slave/test', 06:49:35 INFO - 'SHELL': '/bin/bash', 06:49:35 INFO - 'SHLVL': '1', 06:49:35 INFO - 'TERM': 'linux', 06:49:35 INFO - 'TMOUT': '86400', 06:49:35 INFO - 'USER': 'cltbld', 06:49:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:35 INFO - '_': '/tools/buildbot/bin/python'} 06:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:49:35 INFO - Cleaning up... 06:49:35 INFO - Return code: 0 06:49:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:49:35 INFO - Using env: {'DISPLAY': ':0', 06:49:35 INFO - 'HOME': '/home/cltbld', 06:49:35 INFO - 'LANG': 'en_US.UTF-8', 06:49:35 INFO - 'LANGUAGE': 'en_US:en', 06:49:35 INFO - 'LOGNAME': 'cltbld', 06:49:35 INFO - 'MAIL': '/var/mail/cltbld', 06:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:35 INFO - 'MOZ_NO_REMOTE': '1', 06:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:35 INFO - 'NO_EM_RESTART': '1', 06:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:35 INFO - 'PWD': '/builds/slave/test', 06:49:35 INFO - 'SHELL': '/bin/bash', 06:49:35 INFO - 'SHLVL': '1', 06:49:35 INFO - 'TERM': 'linux', 06:49:35 INFO - 'TMOUT': '86400', 06:49:35 INFO - 'USER': 'cltbld', 06:49:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:35 INFO - '_': '/tools/buildbot/bin/python'} 06:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:49:35 INFO - Cleaning up... 06:49:35 INFO - Return code: 0 06:49:35 INFO - Running post-action listener: _resource_record_post_action 06:49:35 INFO - Running post-action listener: _start_resource_monitoring 06:49:35 INFO - Starting resource monitoring. 06:49:35 INFO - [mozharness: 2017-01-10 14:49:35.957561Z] Finished create-virtualenv step (success) 06:49:35 INFO - [mozharness: 2017-01-10 14:49:35.958010Z] Running install step. 06:49:35 INFO - Running pre-action listener: _resource_record_pre_action 06:49:35 INFO - Running main action method: install 06:49:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:49:36 INFO - Reading from file tmpfile_stdout 06:49:36 INFO - Detecting whether we're running mozinstall >=1.0... 06:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:49:36 INFO - Reading from file tmpfile_stdout 06:49:36 INFO - Output received: 06:49:36 INFO - Usage: mozinstall [options] installer 06:49:36 INFO - Options: 06:49:36 INFO - -h, --help show this help message and exit 06:49:36 INFO - -d DEST, --destination=DEST 06:49:36 INFO - Directory to install application into. [default: 06:49:36 INFO - "/builds/slave/test"] 06:49:36 INFO - --app=APP Application being installed. [default: firefox] 06:49:36 INFO - mkdir: /builds/slave/test/build/application 06:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:49:53 INFO - Reading from file tmpfile_stdout 06:49:53 INFO - Output received: 06:49:53 INFO - /builds/slave/test/build/application/firefox/firefox 06:49:53 INFO - Running post-action listener: _resource_record_post_action 06:49:53 INFO - [mozharness: 2017-01-10 14:49:53.201826Z] Finished install step (success) 06:49:53 INFO - [mozharness: 2017-01-10 14:49:53.202074Z] Running run-tests step. 06:49:53 INFO - Running pre-action listener: _resource_record_pre_action 06:49:53 INFO - Running main action method: run_tests 06:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:49:53 INFO - Python 2.7.3 06:49:53 INFO - Return code: 0 06:49:53 INFO - grabbing minidump binary from tooltool 06:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:49:53 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 0x10a83f0>, '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 0x10a8cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a91a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:49:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:49:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:49:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:49:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:49:53 INFO - Return code: 0 06:49:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:49:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:49:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:49:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:49:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:49:53 INFO - Using env: {'DISPLAY': ':0', 06:49:53 INFO - 'HOME': '/home/cltbld', 06:49:53 INFO - 'LANG': 'en_US.UTF-8', 06:49:53 INFO - 'LANGUAGE': 'en_US:en', 06:49:53 INFO - 'LOGNAME': 'cltbld', 06:49:53 INFO - 'MAIL': '/var/mail/cltbld', 06:49:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:49:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:53 INFO - 'MOZ_NO_REMOTE': '1', 06:49:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:49:53 INFO - 'NO_EM_RESTART': '1', 06:49:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:53 INFO - 'PWD': '/builds/slave/test', 06:49:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:49:53 INFO - 'SHELL': '/bin/bash', 06:49:53 INFO - 'SHLVL': '1', 06:49:53 INFO - 'TERM': 'linux', 06:49:53 INFO - 'TMOUT': '86400', 06:49:53 INFO - 'USER': 'cltbld', 06:49:53 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944', 06:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:53 INFO - '_': '/tools/buildbot/bin/python'} 06:49:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:49:53 INFO - mozversion application_buildid: 20170110141100 06:49:53 INFO - mozversion application_changeset: 347892e394570ca7f3f1fe954d813ac2c7a32288 06:49:53 INFO - mozversion application_display_name: Nightly 06:49:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:49:53 INFO - mozversion application_name: Firefox 06:49:53 INFO - mozversion application_remotingname: firefox 06:49:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 06:49:53 INFO - mozversion application_vendor: Mozilla 06:49:53 INFO - mozversion application_version: 53.0a1 06:49:53 INFO - mozversion platform_buildid: 20170110141100 06:49:53 INFO - mozversion platform_changeset: 347892e394570ca7f3f1fe954d813ac2c7a32288 06:49:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 06:49:53 INFO - mozversion platform_version: 53.0a1 06:49:53 INFO - using testdate: 1484059793 06:49:53 INFO - actual date: 1484059793 06:49:53 INFO - starting webserver on 'localhost:49374' 06:49:53 INFO - SUITE-START | Running 2 tests 06:49:53 INFO - TEST-START | dromaeo_css 06:49:53 INFO - operating with platform_type : linux_ 06:49:53 INFO - Initialising browser for dromaeo_css test... 06:49:53 INFO - TEST-INFO | started process 4871 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAKkTJl/profile http://localhost:49374/getInfo.html) 06:49:57 INFO - PROCESS | 4871 | __metrics Screen width/height:1600/1200 06:49:57 INFO - PROCESS | 4871 | colorDepth:24 06:49:57 INFO - PROCESS | 4871 | Browser inner width/height: 1024/697 06:49:57 INFO - PROCESS | 4871 | __metrics 06:49:58 INFO - PROCESS | 4871 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:49:58 INFO - PROCESS | 4871 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:49:59 INFO - PROCESS | 4871 | console.error: PushService: 06:49:59 INFO - PROCESS | 4871 | stateChangeProcessEnqueue: Error transitioning state 06:49:59 INFO - PROCESS | 4871 | UnknownError 06:49:59 INFO - TEST-INFO | 4871: exit 0 06:49:59 INFO - Browser initialized. 06:49:59 INFO - Running cycle 1/1 for dromaeo_css test... 06:49:59 INFO - TEST-INFO | started process 4954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAKkTJl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:50:11 INFO - PROCESS | 4954 | RSS: Main: 152055808 06:50:11 INFO - PROCESS | 4954 | 06:53:17 INFO - PROCESS | 4954 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49374/tests/dromaeo/cssquery-ext.html) 06:53:18 INFO - PROCESS | 4954 | RSS: Main: 255070208 06:53:18 INFO - PROCESS | 4954 | 06:55:00 INFO - PROCESS | 4954 | ************************* 06:55:00 INFO - PROCESS | 4954 | A coding exception was thrown and uncaught in a Task. 06:55:00 INFO - PROCESS | 4954 | 06:55:00 INFO - PROCESS | 4954 | Full message: ReferenceError: fetch is not defined 06:55:00 INFO - PROCESS | 4954 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 06:55:00 INFO - PROCESS | 4954 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 06:55:00 INFO - PROCESS | 4954 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 06:55:00 INFO - PROCESS | 4954 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:55:00 INFO - PROCESS | 4954 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:55:00 INFO - PROCESS | 4954 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:55:00 INFO - PROCESS | 4954 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 06:55:00 INFO - PROCESS | 4954 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:55:00 INFO - PROCESS | 4954 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:55:00 INFO - PROCESS | 4954 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:55:00 INFO - PROCESS | 4954 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 06:55:00 INFO - PROCESS | 4954 | 06:55:00 INFO - PROCESS | 4954 | ************************* 06:56:21 INFO - PROCESS | 4954 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-ext.html (next: http://localhost:49374/tests/dromaeo/cssquery-jquery.html) 06:56:21 INFO - PROCESS | 4954 | RSS: Main: 248307712 06:56:21 INFO - PROCESS | 4954 | 06:59:24 INFO - PROCESS | 4954 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49374/tests/dromaeo/cssquery-mootools.html) 06:59:24 INFO - PROCESS | 4954 | RSS: Main: 187392000 06:59:24 INFO - PROCESS | 4954 | 07:02:26 INFO - PROCESS | 4954 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49374/tests/dromaeo/cssquery-prototype.html) 07:02:27 INFO - PROCESS | 4954 | RSS: Main: 185344000 07:02:27 INFO - PROCESS | 4954 | 07:05:30 INFO - PROCESS | 4954 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49374/tests/dromaeo/cssquery-yui.html) 07:05:31 INFO - PROCESS | 4954 | RSS: Main: 184500224 07:05:31 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-yui.html (next: http://localhost:49374/tests/dromaeo/cssquery-dojo.html) 07:08:34 INFO - PROCESS | 4954 | RSS: Main: 270274560 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | __start_tp_report 07:08:34 INFO - PROCESS | 4954 | _x_x_mozilla_page_load 07:08:34 INFO - PROCESS | 4954 | _x_x_mozilla_page_load_details 07:08:34 INFO - PROCESS | 4954 | |i|pagename|runs| 07:08:34 INFO - PROCESS | 4954 | |0;dojo.html;2580.8709564521773;2597.5973724072774;2625.973740262598;2640.353113487196;2650.2579277802224;4656.580907718313;4704.364910737049;4716.174669432853;4729.219678753006;4760.761961901909;4719.528047195278;4728.148933192024;4772.832950846721;4783.043391321732;4807.999999999991;4297.978510107445;4319.805608747614;4344.9348259776125;4391.143726973241;4463.285874260119;4056.026553627123;4386.56134386562;4431.091626216634;4451.554844515555;4505.07645932583;4265.573442655741;4306.418633484503;4381.649468042581;4390.912181756347;4471.575200355961;4328.61042506176;4339;4386.649068074546;4404.273294906337;4468.106378724268;4374.934375984363;4376.190404775095;4400.339949007643;4415.4259946206785;4431.667624928144;4733.408323959505;4742.644301677354;4760.0479904018985;4777.856664300077;4817.590504807061;4999.075171093323;5044.2937988681615;5055.772490237948;5109.795608175668;5116.744162791882;5055.873603159914;5113.411957624883;5122.410922743895;5127.8205262816;5128.205128205134;4781.402324709411;4806.519348065235;4832.44426890912;4870.805167793319;4877;5216.26972223889;5218.947810521846;5236.92144617831;5243.161094224905;5276.709780962084;4883.658143929962;4913.115639184981;4981.875453113628;5018.272350509229;5037.571806396424;4953.281774142729;4959.900801984012;5050.267711181858;5096.9490305097215;5104.03023425548;5003.774830132653;5006.048850718352;5009.674371165863;5010.148274793294;5028.949710502849;5009.674371165863;5028.069807085701;5030.4466508684745;5068.467810879878;5125.384953805567;4258.254805409041;4294.377315289328;4303.440552728125;4304.3543468479975;4339.783010849508;3675.6324367563566;3739.8878033659034;3776.660100590905;3799.5440547133967;3829.7319187657154;4333.71830830995;4352.564743525685;4373.671974601918;4387.14450682114;4452.888677783081;5125.051865404912;5126.282320475137;5126.384833819965;5126.846194614168;5178.300929374486;4404.031113155165;4410.0738844842945;4413.337999300131;4413.933790993137;4456.064226512469;4449.822007119679;4498.932516012263;4498.942748454111;4540.682152249311;4541.568550987651;4775.426948766031;4779.761011949458;4783.449903261169;4793.089313030513;4824.517548245148;5087.720175390241;5105.566026887756;5107.310513080686;5107.336046313955;5108.386993560796;4696.976515117402;4725.787339569796;4727.456342520517;4784.832530861403;4788.209945358977;4959.97520012412;4986.551210391082;5018.1469150245275;5018.824341148043;5020.397552293748;5032.370953630796;5062.443131255485;5066.214736715815;5095.0574143783515;5097.515736005073;4954.232094025432;4966.329545511309;4966.900661986812;4972.651914366104;5021.874453138701;4956.083124621957;4960.553550180501;4961.8511444656715;4964.255361695775;5010.098182327216;4756.6908150970075;4844.176489996779;4852.102361063215;4871.318015477907;4877.609791216765;4958.330625365529;4999;5003.524665156804;5006.299118123538;5039.697618142924;4867.123917694707;4869.537393947569;4869.70781753096;4876.049170411878;4906.018796240837 07:08:34 INFO - PROCESS | 4954 | |1;ext.html;536.8496820890151;546.2680008788222;553.7037684838613;562.4881357964254;563.399979022341;31873.840630796843;32731.345373092525;34391.65608343916;34486.65513344866;37246;35716.2856742865;36109.81945090271;36128.638713612854;36202.63797362026;36206.818965905135;7666.8849967250535;7848.175941526115;7854.253845884632;7858.29275365217;7890.921090789076;7603.0496187976505;7794.766157015299;9967.601295948154;10225.642102526414;10257.281990260684;9819.705408837746;9878.407295562289;9883.406995580288;9889.406635601887;9905.158061564776;10296.588136474533;10349.482525873751;10351.844722329171;10356.637517686844;10372.896271037269;9782.461964591945;9833.213342932548;9859.950700246525;9860.506974651238;9861.852072218922;39249.41125883114;39512;39592.80203598963;39618.603813962065;39683.20633587312;3376.6623337666283;3438.2607739336163;3465.740069494773;3477.739169562293;3477.8434970426392;29730.851345743133;29936.90441669084;30329.24176895595;30389.544156837666;30502.54246186309;3147.35479226758;3161.351922855809;3226.3386005868742;3246.480563109891;3332.9833350833333;243370;243692;244472;244736;245251;54716.45283547114;54864.177037344474;54897.725511372984;55027.44972550304;55044.44955550473;58879.41120588825;58986.70506647525;59078.70460647669;59097;59104.704476477345;13200.14199077057;13697.356317241994;13701.314934253447;13714.519991800438;13741.312934353242;8613.181747733955;8877.911220887838;10257.948710256402;10363.585456581803;10368.118709909591;15058.774118388234;15165.317560709795;15224.553667401578;16722.581935451468;16959;6476.158099447041;6572.638504882278;6696.296888826603;6726.981905428352;6741.59550426976;816.5957850863861;833.8492879825801;846.0397448895425;856.4347530629755;866.956652167389;27225.727742722716;27583.31041723973;28026.29934251619;28689.28276793097;28697.426051478855;886.0873300500494;973.5570315506427;985.5762022330323;985.9260555458369;994.6916455898695;14749.631259218606;14878.256087195598;15153.621159471102;15285.565733107152;15305.387784488496;339659;343368;343872;346583;347278;52409.47590524122;52674;52914;52923.47076529262;53005;1240.2124650846597;1267.6830792301926;1281.9294938778457;1287.5557932513454;1293.6959814443599;25540;26417.867910661094;26520.602190966765;27612;27623.171304860887;42352.364714529926;43048.35427468653;43066.354004690584;43086.35370469508;43141.784291078344;189432;190339;191443;191692;192288.7711228866;36081.45877811783;36118.27763444769;36189.457158143174;36551.63448365482;36578.634213657526;40488;41022.58977410307;41309.58690413057;42068;42105.36841947312;33812.3237535243;35190.648093518736;35474.46788298126;35629;35647;34442.48336275008;34485.655143448246;34544.654553454144;34701.65298347085;34736.478952815225;33341;34029.31941361109;34041.82979085089;34133;34210;28156.24250179983;28573.714262857105;31531.68468315379;31802.681973180897;31923.521147182346;22799.730356682147;23057.884710576338;23321.416964576023;23465.88267058654;23558 07:08:34 INFO - PROCESS | 4954 | |2;jquery.html;353.8160156718507;357.46915829992463;360.3333832410042;365.4609451059689;390.37150188197;13578.728425431485;17125.143742812856;17577.1211439428;18349.44951651449;18539.35112271071;16562.917185414055;17083.829161708378;17308;17382.304707811705;17424.82575174248;4831.130396528614;5234.528892399683;5260.316158899338;5268.973655131719;5269.76286067126;3921.2549615573093;4108.506979162505;5189.636725429222;5294.232336311233;5298.973505132469;5819.854503637422;6081.665508397036;6103.45068943795;6208;6222.9688851555675;6060.666663333515;6185.226846644169;6197.504199664038;6241.469475094623;6292.653904035276;5510.531604813596;5525.226468294442;5544.722763861791;5548.223248745179;5556.888862222773;21280.255191068394;21518.246861359778;21658.675119873216;21753.782462175335;21836.563268734695;2536.746325367467;2566.0762125634756;2593.7017243016917;2609.308533238693;2641.2340421277804;13624.523141690092;13854.238016909067;13939.930300348433;14015.50945716895;14333.641658958506;1763.3916298876868;1817.4547635709234;1850.1951651031845;1851.1392202625843;1932.5651728361147;79350;80177.9002380984;80892.19107808906;81200.59399703023;83672.16327836766;10415.323004004715;10644.201684873666;10780.353178809297;10781.407022613676;10814.459277036116;14739.92630036843;14764.557063288119;14774.335154917839;14780.260986950609;14806.851931480547;6661.633610151488;6813.625250611165;6933.5493192942295;6951.756688515878;6980.162380514371;6080.331163572004;6154.476869466056;6288.8427789304715;6320.968395158086;6334.493240540745;4410.117976404667;4527.818887244473;4528.6150677192145;4627.953720462752;4629.8148074077335;1845.7462173657655;1869.9626007479774;1884.5100273928604;1884.8825438933088;1920.9135588898532;908.7909780750465;911.0980129671577;938.202527851321;944.7354740672486;968.7917097823861;16596;16809;16849.831501685072;17723.11384430799;17838.643227135384;912.7079334612993;916.2807196350833;918.1828172926101;928.7863791327898;933;9291.535423228812;9480.194183494343;9690.321677482509;9697.951510242403;9815.067568580973;451073;454271;458847;465037;465894;87869;88929;89910;91848.54075729579;91930;913.0371943044729;918.3020904112789;944.2870632672289;944.8441007233866;947.3605316411395;14527.201003944885;14544;14691.191984440959;14983.40066397332;15472.458463953708;38414.62195292717;39065.28470583568;39098.2180356397;39263.607363927134;39744.40383394309;52495.21257181222;52736.47263527316;53573;55249.1712624319;55829.72085139548;7615.010048693634;7649.349805266615;7671.769846904602;7673.462857600138;7838.490498117604;8790.604422800861;11653;11728.179027468337;11797.35114568707;11863.822042669195;9157.816843663222;9561.952190239004;9638.9036109638;10400;10422.270441069355;10504.632337868137;10600.522976465923;10629.202809789142;10714.303570267908;11057.889421106007;9962.651307204213;10162.491875406347;10451.947740261554;10525.421101839473;10809.891901081202;7802.804929876572;7827;7955.4828936118965;8283.37874659415;8286.337093032422;9531.761705957406;9996.500174991075;10025.799484010426;10035.949820250851;10140.898591014291 07:08:34 INFO - PROCESS | 4954 | |3;mootools.html;1369.6644322141076;1382.854800245974;1383.5295999360214;1388.2087210290138;1401.572520381284;6949.965250173748;7367.447441441893;7381.778546643596;7389.150247721514;7390.704371825121;6362.713677884495;6521.869562608745;7010.964945175267;7167.713291468335;7222.674979625916;2171.9782802172012;2207.293666026872;2210.900509477069;2213.1258153029544;2216.92240771573;2105.26315789474;2187.5187458759046;2199.25225423356;2209.7790220977936;2209.790069943361;1893.8863668179883;1964.9705254421194;2088.6866969954553;2135.996081841532;2151.075037733774;2150.827933765295;2158.1583182558816;2164.588728141648;2178.6731990201597;2183.355910006544;2159.4169574214875;2169.4901698100934;2176.5973294940486;2177.444751588335;2178.2593918067773;7121.359077683033;7152.9642351788425;7192.100987376578;7207.49547531673;7212.386947109502;4336.284513055373;4468.910621787546;4470.441194850644;4491.1466821304175;4495.865124046284;2753.9810270200155;2811.831290122599;2865.885364585414;2866.4123854609757;2866.484032874082;1055.757175849559;1121.669107613256;1129.9604513841975;1136.102479041556;1162.7790719763302;9688.370255933341;9693.642889995406;9696.369735967139;9699.563519641562;9702.805943881083;3968.3849003404516;4125.1749650070105;4141.5237247716295;4185.267578173807;4210.915781684349;7715.961420192863;7762.456628035983;7768.572728499987;7771.10632277284;7772.417068719868;4410.889727756832;4445.777711114496;4447.3329000650165;4449.710768800018;4451.977740111279;3857.884263472044;3969.4641223435046;4002.739821911625;4151.12826306473;4151.501819781645;3246.5130230465147;3331.566896303465;3349.3468773588916;3353.714934230569;3373.3421982713135;1024.784795193003;1029.264076185531;1031.7626945802508;1032.5301987595626;1041.1358572385054;648.1088503307951;648.8385789436855;654.2443477783168;669.0399277037467;688.7761477519826;4828.517148285144;4885;4983.152864013126;5025.723585202776;5116.641835071584;919.4069824962861;946.4841661294611;955.1212884146602;967.8790151231096;968.3899143539525;3218.1311046017913;3228.2090887732656;3278.983605082007;3311.6357200707903;3328.4674452088034;8325.292350150305;8790.956045219733;8803.295736341077;8807.119288071142;8828.823423531494;8743.38796284254;8768.561571921378;8775.868361974575;8779.341549383822;8781.956090219635;6842.794716158523;6933.757318493828;6934.098567186234;6935.3411425914455;6939.375456208965;4665.720056796603;4769.141554520081;4790.473047964651;4820.276958456119;4852.927206091841;7780.92219077802;7948.16544262844;8005.599720014093;8018.719344822904;8029.237222463857;8123.228293312126;8131.552764598004;8205.097439281555;8218.712345067894;8355.874661880198;6141.170941922962;6252.249730032425;6281.434670879643;6293.842653933688;6294.622322660655;6566.24488183865;6732.192136943598;6741.797746067626;6786.59280443175;6787.321267873371;6213.2233470816145;6271.404216599416;6276.340984196776;6278.968605156945;6362.268339141058;6145.231846019247;6218.9378106220165;6234.9688251558455;6270.529710271657;6271.4669253114;5418.972905135449;5541.833744987657;5589.860253493533;5597.608167428241;5642.379338272869;4095.4675892135024;4099.307159353359;4119.629233369011;4152.4601801765575;4234.237837189212;4188.560201178954;4255.744655320566;4320.848770293024;4328.848490302824;4406.823727050882 07:08:34 INFO - PROCESS | 4954 | |4;prototype.html;349.37636319170286;355.46502513716854;356.54362416107375;358.63598447575697;366.71213097718294;4888.437829649592;4888.902221955559;4915.893923867133;4922.581580565652;4929.753512324383;4821.807127714887;4823.589994850442;4834.4923783002805;4843.39457567804;4861.7812198451065;3692.335379631665;3804.5434547854297;3808.1812410331772;3817.866374676887;3896.629820167094;3888.411674224414;3891.221755648867;3897.9025524361978;3898.1813819097883;3911.5892831252736;3877.1857909838827;3887.378019516878;3896.2597106549665;3918.490596222487;3923.6664883484937;3732.757370770905;3772.603876592973;3842.675181515171;3863.16941857502;3940.4483372327754;3499.597546282185;3506.947395789065;3509.9122521937156;3513.5959364673135;3515.209077957439;4708.223143181371;4722.433308003027;4729.905401891943;4731.881702957454;4799.2801079837955;4302.569743025703;4466.955330446686;4484.080763443486;4489.03485750562;4503.864884053483;5053.84838454847;5077.517635824628;5117.385913690381;5138.794448222066;5138.845834624967;3874.6900247980093;3887.086534664352;3893.5911729268587;3896.123372241251;3903.6877049835944;5084.44071152173;5320.334958130234;5326.387465441447;5350.438203988603;5386.5994841341535;3769.7738135711943;3813.237352529505;3825.215830754689;3829.942550861739;3835.3096442640594;3895.5130608673917;3897.863574774926;3901.122247494348;3903.2193561287854;3905.1799122184093;4378.43080399546;4394.143142087262;4403.295472724413;4403.647708183402;4406.735595864258;4995.775190116454;5093.573799336192;5105.264210168561;5111.233315002779;5142.717150556755;1340.5777180188209;1341.4164838295374;1345.7282867690135;1347.6294019144814;1349.190485708567;904.5929331800716;962.6979361542637;985.3250523391499;994.6071301835879;1003.0420947994647;4256.574342565718;4392.472903251631;4485.887852803706;4507.2112380333965;4517.841875534341;5261.605379596545;5263.210508427153;5263.447338029452;5269.811763882873;5275.182346736262;4370.147821174841;4499.212637788374;4501.392312037833;4512.142692888407;4525;2669.826461280011;2682.5573780326026;2688.6639170103736;2698.406350602864;2699.5275826730635;4759.333693282943;4766.666333356632;4781.95218047822;4783.7129772213775;4792.808287668524;4999.525045120708;5190.8183213587345;5205.323307970016;5214.45248248936;5226.686398816083;4728.682945356751;4732.384789977281;4754.405699287589;4764.571188593043;4780.330753694486;5074.873128171677;5080.364954380702;5088.363954505687;5108.821191258288;5115.56517696;4748.928766068442;4937.28409380645;4988.7777494514075;4989.027139707868;5009.649324547392;4712.835050773207;4732.119661877227;4740.976295118502;4742.596879265163;4792.209285467999;3766;3769.9444155636465;3849.82675779585;3854.4989151410136;3854.903627409225;4943.283223932438;5095.312132862016;5105.668131571436;5113.130767769562;5120.334356533627;5083.237514372874;5086.516780905808;5096.770645320896;5107.284980102714;5137.8715532112;5049.621278404208;5069.7211653357945;5091.159958606937;5099.796008159632;5107.259447380184;4822.397200349957;5006.220303670705;5007.499250074963;5010.448850626355;5038.521340472649;4885.316055752269;4928.8767780805765;5005.1241032819835;5087.23691446286;5108.28484012246;4989.675671081368;4991.482708149131;4995.400551933792;4995.825146119869;5013.273075404119 07:08:34 INFO - PROCESS | 4954 | |5;yui.html;670.9228438729541;691.3466773898667;738.9298016688416;771.4137255685678;792.1761368177098;12767.553135640255;13953.651158721037;13977.161370317788;14070.57788266351;14085.295735213236;13604.795928061087;13801.792973105412;13802.102863313903;13815.930920345385;13816.861831381682;658.9967050164743;674.6154691825661;746.4998451037818;764.5183534373336;798.7843282313772;807.4428644235488;809.2231457800518;815.1115284340068;816.4937738602075;816.8652172391578;863.2489733931488;863.8574635185187;864.5893200729635;865.2645251536206;865.6580650642992;693.6254422611784;713.8286811165283;859.5100792548219;859.6002858670716;859.9441036332619;526.5445389737887;526.6497778977014;527.4435470578538;604.0757640809568;619.2383368456823;13251.204927704272;13304.534341298007;13348.33258337089;13382.464701411931;13412.530561430303;12816.231026138461;12846.935765321206;13147.86852131476;13149.934250328783;13184.274864882427;10854.34873907568;10876.673699789018;10983.286086404358;10996.28524145928;11072.335659860435;4091.161311931047;4099.077707515815;4104.692148088905;4118.814653340607;4164.3961625564425;61376;64404.677976610285;64866.3513364865;66585.99999999952;66857.66571167113;10309.690709278733;10391.948040259751;10500.894991050145;10666.78666426667;10816.72958176036;20222.19111235534;20321.18715251403;20433.080511377022;20561.588768224552;20626.58746825085;6272.2159730033745;6312.147860038836;6430.131932189188;6485.189351331083;6564.67176641166;3359.3617212729505;3362.2939182771893;3375.814330211862;3417.79493230407;3428.177237462991;2842.4173044525824;2918.343372741116;2941.1323659520494;2942.190897503161;2951.8081324713826;500.7721486723532;503.2375950435094;504.08456759922285;507.9339685840817;509.5083244668912;478.78693981178736;497.1895809830026;504.48290502234767;515.8288161184084;523.6674711558188;7599.138211138306;7684.154742978268;8061.717839875693;8138.186181381814;8298.792530186794;489.68415372084803;490.208190232403;498.9176785830297;544.8120398462524;547.7891011960344;3593.299306635181;3609.5848977367414;3614.253506514242;3698.68561172298;3718.925621487609;51345.743271283405;54354.184687229725;56214.156787648215;56752.43247567554;61529.69235153795;47553.286700699515;56413.71793141008;56652.58368540819;58095.419045809846;59985.400145997985;47904.04191916142;49400.25899611437;52753.736231319366;52795.47204527982;52896.20655690168;8378.204070613281;8843.955780221058;9185.724428267162;9339.299552533452;9413.905860941302;36602.633973659926;38346.42480362853;38994.805025975824;40129.1974160521;40156.79921600373;46755.53244467512;48424.27363589479;48806.511934880196;50371.74814125906;50479.49520504748;11571.92229769977;11583.76832463363;11655.30068195911;11875.109366797353;12323.198992065487;11919;11993.64019079429;12250.693732656755;12741.235525868478;12986;11921.344326061802;11927.76144477123;12582.748345033231;12731.681707957005;12746.553870614483;10621.575136994434;10728.89271107279;10860.728481788019;11230.04544613717;11233.55065797359;11291.43542822872;11437.370944598128;11530.481128349413;11667.824982625098;12320.81518777237;9924.255680823822;9926.652567160114;10112.646057387956;10245.231607629308;10539.15686745074;9647.807043859224;10215;10303.53634086453;10400.323978941344;10439.739006524898 07:08:34 INFO - PROCESS | 4954 | __end_tp_report 07:08:34 INFO - PROCESS | 4954 | __start_cc_report 07:08:34 INFO - PROCESS | 4954 | _x_x_mozilla_cycle_collect,1141 07:08:34 INFO - PROCESS | 4954 | __end_cc_report 07:08:34 INFO - PROCESS | 4954 | __startTimestamp1484060914729__endTimestamp 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | ------- Summary: start ------- 07:08:34 INFO - PROCESS | 4954 | Number of tests: 6 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | [#0] dojo.html Cycles:165 Average:4694.94 Median:4824.52 stddev:496.44 (10.3%) stddev-sans-first:469.45 07:08:34 INFO - PROCESS | 4954 | Values: 2580.9 2597.6 2626.0 2640.4 2650.3 4656.6 4704.4 4716.2 4729.2 4760.8 4719.5 4728.1 4772.8 4783.0 4808.0 4298.0 4319.8 4344.9 4391.1 4463.3 4056.0 4386.6 4431.1 4451.6 4505.1 4265.6 4306.4 4381.6 4390.9 4471.6 4328.6 4339.0 4386.6 4404.3 4468.1 4374.9 4376.2 4400.3 4415.4 4431.7 4733.4 4742.6 4760.0 4777.9 4817.6 4999.1 5044.3 5055.8 5109.8 5116.7 5055.9 5113.4 5122.4 5127.8 5128.2 4781.4 4806.5 4832.4 4870.8 4877.0 5216.3 5218.9 5236.9 5243.2 5276.7 4883.7 4913.1 4981.9 5018.3 5037.6 4953.3 4959.9 5050.3 5096.9 5104.0 5003.8 5006.0 5009.7 5010.1 5028.9 5009.7 5028.1 5030.4 5068.5 5125.4 4258.3 4294.4 4303.4 4304.4 4339.8 3675.6 3739.9 3776.7 3799.5 3829.7 4333.7 4352.6 4373.7 4387.1 4452.9 5125.1 5126.3 5126.4 5126.8 5178.3 4404.0 4410.1 4413.3 4413.9 4456.1 4449.8 4498.9 4498.9 4540.7 4541.6 4775.4 4779.8 4783.4 4793.1 4824.5 5087.7 5105.6 5107.3 5107.3 5108.4 4697.0 4725.8 4727.5 4784.8 4788.2 4960.0 4986.6 5018.1 5018.8 5020.4 5032.4 5062.4 5066.2 5095.1 5097.5 4954.2 4966.3 4966.9 4972.7 5021.9 4956.1 4960.6 4961.9 4964.3 5010.1 4756.7 4844.2 4852.1 4871.3 4877.6 4958.3 4999.0 5003.5 5006.3 5039.7 4867.1 4869.5 4869.7 4876.0 4906.0 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | [#1] ext.html Cycles:180 Average:42697.45 Median:190989.83 stddev:70102.93 (36.7%) stddev-sans-first:70228.12 07:08:34 INFO - PROCESS | 4954 | Values: 536.8 546.3 553.7 562.5 563.4 31873.8 32731.3 34391.7 34486.7 37246.0 35716.3 36109.8 36128.6 36202.6 36206.8 7666.9 7848.2 7854.3 7858.3 7890.9 7603.0 7794.8 9967.6 10225.6 10257.3 9819.7 9878.4 9883.4 9889.4 9905.2 10296.6 10349.5 10351.8 10356.6 10372.9 9782.5 9833.2 9860.0 9860.5 9861.9 39249.4 39512.0 39592.8 39618.6 39683.2 3376.7 3438.3 3465.7 3477.7 3477.8 29730.9 29936.9 30329.2 30389.5 30502.5 3147.4 3161.4 3226.3 3246.5 3333.0 243370.0 243692.0 244472.0 244736.0 245251.0 54716.5 54864.2 54897.7 55027.4 55044.4 58879.4 58986.7 59078.7 59097.0 59104.7 13200.1 13697.4 13701.3 13714.5 13741.3 8613.2 8877.9 10257.9 10363.6 10368.1 15058.8 15165.3 15224.6 16722.6 16959.0 6476.2 6572.6 6696.3 6727.0 6741.6 816.6 833.8 846.0 856.4 867.0 27225.7 27583.3 28026.3 28689.3 28697.4 886.1 973.6 985.6 985.9 994.7 14749.6 14878.3 15153.6 15285.6 15305.4 339659.0 343368.0 343872.0 346583.0 347278.0 52409.5 52674.0 52914.0 52923.5 53005.0 1240.2 1267.7 1281.9 1287.6 1293.7 25540.0 26417.9 26520.6 27612.0 27623.2 42352.4 43048.4 43066.4 43086.4 43141.8 189432.0 190339.0 191443.0 191692.0 192288.8 36081.5 36118.3 36189.5 36551.6 36578.6 40488.0 41022.6 41309.6 42068.0 42105.4 33812.3 35190.6 35474.5 35629.0 35647.0 34442.5 34485.7 34544.7 34701.7 34736.5 33341.0 34029.3 34041.8 34133.0 34210.0 28156.2 28573.7 31531.7 31802.7 31923.5 22799.7 23057.9 23321.4 23465.9 23558.0 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | [#2] jquery.html Cycles:180 Average:27297.56 Median:231737.48 stddev:75890.71 (32.7%) stddev-sans-first:76076.64 07:08:34 INFO - PROCESS | 4954 | Values: 353.8 357.5 360.3 365.5 390.4 13578.7 17125.1 17577.1 18349.4 18539.4 16562.9 17083.8 17308.0 17382.3 17424.8 4831.1 5234.5 5260.3 5269.0 5269.8 3921.3 4108.5 5189.6 5294.2 5299.0 5819.9 6081.7 6103.5 6208.0 6223.0 6060.7 6185.2 6197.5 6241.5 6292.7 5510.5 5525.2 5544.7 5548.2 5556.9 21280.3 21518.2 21658.7 21753.8 21836.6 2536.7 2566.1 2593.7 2609.3 2641.2 13624.5 13854.2 13939.9 14015.5 14333.6 1763.4 1817.5 1850.2 1851.1 1932.6 79350.0 80177.9 80892.2 81200.6 83672.2 10415.3 10644.2 10780.4 10781.4 10814.5 14739.9 14764.6 14774.3 14780.3 14806.9 6661.6 6813.6 6933.5 6951.8 6980.2 6080.3 6154.5 6288.8 6321.0 6334.5 4410.1 4527.8 4528.6 4628.0 4629.8 1845.7 1870.0 1884.5 1884.9 1920.9 908.8 911.1 938.2 944.7 968.8 16596.0 16809.0 16849.8 17723.1 17838.6 912.7 916.3 918.2 928.8 933.0 9291.5 9480.2 9690.3 9698.0 9815.1 451073.0 454271.0 458847.0 465037.0 465894.0 87869.0 88929.0 89910.0 91848.5 91930.0 913.0 918.3 944.3 944.8 947.4 14527.2 14544.0 14691.2 14983.4 15472.5 38414.6 39065.3 39098.2 39263.6 39744.4 52495.2 52736.5 53573.0 55249.2 55829.7 7615.0 7649.3 7671.8 7673.5 7838.5 8790.6 11653.0 11728.2 11797.4 11863.8 9157.8 9562.0 9638.9 10400.0 10422.3 10504.6 10600.5 10629.2 10714.3 11057.9 9962.7 10162.5 10451.9 10525.4 10809.9 7802.8 7827.0 7955.5 8283.4 8286.3 9531.8 9996.5 10025.8 10035.9 10140.9 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | [#3] mootools.html Cycles:180 Average:4749.55 Median:4868.96 stddev:2553.73 (52.4%) stddev-sans-first:2548.27 07:08:34 INFO - PROCESS | 4954 | Values: 1369.7 1382.9 1383.5 1388.2 1401.6 6950.0 7367.4 7381.8 7389.2 7390.7 6362.7 6521.9 7011.0 7167.7 7222.7 2172.0 2207.3 2210.9 2213.1 2216.9 2105.3 2187.5 2199.3 2209.8 2209.8 1893.9 1965.0 2088.7 2136.0 2151.1 2150.8 2158.2 2164.6 2178.7 2183.4 2159.4 2169.5 2176.6 2177.4 2178.3 7121.4 7153.0 7192.1 7207.5 7212.4 4336.3 4468.9 4470.4 4491.1 4495.9 2754.0 2811.8 2865.9 2866.4 2866.5 1055.8 1121.7 1130.0 1136.1 1162.8 9688.4 9693.6 9696.4 9699.6 9702.8 3968.4 4125.2 4141.5 4185.3 4210.9 7716.0 7762.5 7768.6 7771.1 7772.4 4410.9 4445.8 4447.3 4449.7 4452.0 3857.9 3969.5 4002.7 4151.1 4151.5 3246.5 3331.6 3349.3 3353.7 3373.3 1024.8 1029.3 1031.8 1032.5 1041.1 648.1 648.8 654.2 669.0 688.8 4828.5 4885.0 4983.2 5025.7 5116.6 919.4 946.5 955.1 967.9 968.4 3218.1 3228.2 3279.0 3311.6 3328.5 8325.3 8791.0 8803.3 8807.1 8828.8 8743.4 8768.6 8775.9 8779.3 8782.0 6842.8 6933.8 6934.1 6935.3 6939.4 4665.7 4769.1 4790.5 4820.3 4852.9 7780.9 7948.2 8005.6 8018.7 8029.2 8123.2 8131.6 8205.1 8218.7 8355.9 6141.2 6252.2 6281.4 6293.8 6294.6 6566.2 6732.2 6741.8 6786.6 6787.3 6213.2 6271.4 6276.3 6279.0 6362.3 6145.2 6218.9 6235.0 6270.5 6271.5 5419.0 5541.8 5589.9 5597.6 5642.4 4095.5 4099.3 4119.6 4152.5 4234.2 4188.6 4255.7 4320.8 4328.8 4406.8 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | [#4] prototype.html Cycles:180 Average:4234.28 Median:4745.76 stddev:1181.11 (24.9%) stddev-sans-first:1147.87 07:08:34 INFO - PROCESS | 4954 | Values: 349.4 355.5 356.5 358.6 366.7 4888.4 4888.9 4915.9 4922.6 4929.8 4821.8 4823.6 4834.5 4843.4 4861.8 3692.3 3804.5 3808.2 3817.9 3896.6 3888.4 3891.2 3897.9 3898.2 3911.6 3877.2 3887.4 3896.3 3918.5 3923.7 3732.8 3772.6 3842.7 3863.2 3940.4 3499.6 3506.9 3509.9 3513.6 3515.2 4708.2 4722.4 4729.9 4731.9 4799.3 4302.6 4467.0 4484.1 4489.0 4503.9 5053.8 5077.5 5117.4 5138.8 5138.8 3874.7 3887.1 3893.6 3896.1 3903.7 5084.4 5320.3 5326.4 5350.4 5386.6 3769.8 3813.2 3825.2 3829.9 3835.3 3895.5 3897.9 3901.1 3903.2 3905.2 4378.4 4394.1 4403.3 4403.6 4406.7 4995.8 5093.6 5105.3 5111.2 5142.7 1340.6 1341.4 1345.7 1347.6 1349.2 904.6 962.7 985.3 994.6 1003.0 4256.6 4392.5 4485.9 4507.2 4517.8 5261.6 5263.2 5263.4 5269.8 5275.2 4370.1 4499.2 4501.4 4512.1 4525.0 2669.8 2682.6 2688.7 2698.4 2699.5 4759.3 4766.7 4782.0 4783.7 4792.8 4999.5 5190.8 5205.3 5214.5 5226.7 4728.7 4732.4 4754.4 4764.6 4780.3 5074.9 5080.4 5088.4 5108.8 5115.6 4748.9 4937.3 4988.8 4989.0 5009.6 4712.8 4732.1 4741.0 4742.6 4792.2 3766.0 3769.9 3849.8 3854.5 3854.9 4943.3 5095.3 5105.7 5113.1 5120.3 5083.2 5086.5 5096.8 5107.3 5137.9 5049.6 5069.7 5091.2 5099.8 5107.3 4822.4 5006.2 5007.5 5010.4 5038.5 4885.3 4928.9 5005.1 5087.2 5108.3 4989.7 4991.5 4995.4 4995.8 5013.3 07:08:34 INFO - PROCESS | 4954 | 07:08:34 INFO - PROCESS | 4954 | [#5] yui.html Cycles:180 Average:14844.05 Median:4141.61 stddev:18005.48 (434.7%) stddev-sans-first:18024.53 07:08:34 INFO - PROCESS | 4954 | Values: 670.9 691.3 738.9 771.4 792.2 12767.6 13953.7 13977.2 14070.6 14085.3 13604.8 13801.8 13802.1 13815.9 13816.9 659.0 674.6 746.5 764.5 798.8 807.4 809.2 815.1 816.5 816.9 863.2 863.9 864.6 865.3 865.7 693.6 713.8 859.5 859.6 859.9 526.5 526.6 527.4 604.1 619.2 13251.2 13304.5 13348.3 13382.5 13412.5 12816.2 12846.9 13147.9 13149.9 13184.3 10854.3 10876.7 10983.3 10996.3 11072.3 4091.2 4099.1 4104.7 4118.8 4164.4 61376.0 64404.7 64866.4 66586.0 66857.7 10309.7 10391.9 10500.9 10666.8 10816.7 20222.2 20321.2 20433.1 20561.6 20626.6 6272.2 6312.1 6430.1 6485.2 6564.7 3359.4 3362.3 3375.8 3417.8 3428.2 2842.4 2918.3 2941.1 2942.2 2951.8 500.8 503.2 504.1 507.9 509.5 478.8 497.2 504.5 515.8 523.7 7599.1 7684.2 8061.7 8138.2 8298.8 489.7 490.2 498.9 544.8 547.8 3593.3 3609.6 3614.3 3698.7 3718.9 51345.7 54354.2 56214.2 56752.4 61529.7 47553.3 56413.7 56652.6 58095.4 59985.4 47904.0 49400.3 52753.7 52795.5 52896.2 8378.2 8844.0 9185.7 9339.3 9413.9 36602.6 38346.4 38994.8 40129.2 40156.8 46755.5 48424.3 48806.5 50371.7 50479.5 11571.9 11583.8 11655.3 11875.1 12323.2 11919.0 11993.6 12250.7 12741.2 12986.0 11921.3 11927.8 12582.7 12731.7 12746.6 10621.6 10728.9 10860.7 11230.0 11233.6 11291.4 11437.4 11530.5 11667.8 12320.8 9924.3 9926.7 10112.6 10245.2 10539.2 9647.8 10215.0 10303.5 10400.3 10439.7 07:08:34 INFO - PROCESS | 4954 | -------- Summary: end -------- 07:08:34 INFO - PROCESS | 4954 | 07:08:35 INFO - TEST-INFO | 4954: exit 0 07:08:35 INFO - TEST-OK | dromaeo_css | took 1122101ms 07:08:35 INFO - TEST-START | kraken 07:08:35 INFO - operating with platform_type : linux_ 07:08:35 INFO - Initialising browser for kraken test... 07:08:35 INFO - TEST-INFO | started process 5254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYKgXwD/profile http://localhost:49374/getInfo.html) 07:08:39 INFO - PROCESS | 5254 | __metrics Screen width/height:1600/1200 07:08:39 INFO - PROCESS | 5254 | colorDepth:24 07:08:39 INFO - PROCESS | 5254 | Browser inner width/height: 1024/697 07:08:39 INFO - PROCESS | 5254 | __metrics 07:08:40 INFO - PROCESS | 5254 | 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" 07:08:40 INFO - PROCESS | 5254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:08:41 INFO - PROCESS | 5254 | console.error: PushService: 07:08:41 INFO - PROCESS | 5254 | stateChangeProcessEnqueue: Error transitioning state 07:08:41 INFO - PROCESS | 5254 | UnknownError 07:08:42 INFO - TEST-INFO | 5254: exit 0 07:08:42 INFO - Browser initialized. 07:08:42 INFO - Running cycle 1/1 for kraken test... 07:08:42 INFO - TEST-INFO | started process 5336 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYKgXwD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:54 INFO - PROCESS | 5336 | RSS: Main: 151678976 07:08:54 INFO - PROCESS | 5336 | 07:08:56 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-beat-detection) 07:08:57 INFO - PROCESS | 5336 | RSS: Main: 205291520 07:08:57 INFO - PROCESS | 5336 | 07:09:00 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-dft) 07:09:01 INFO - PROCESS | 5336 | RSS: Main: 185196544 07:09:01 INFO - PROCESS | 5336 | 07:09:04 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-fft) 07:09:04 INFO - PROCESS | 5336 | RSS: Main: 267984896 07:09:04 INFO - PROCESS | 5336 | 07:09:06 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-oscillator) 07:09:07 INFO - PROCESS | 5336 | RSS: Main: 179228672 07:09:07 INFO - PROCESS | 5336 | 07:09:09 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49374/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:09:10 INFO - PROCESS | 5336 | RSS: Main: 182558720 07:09:10 INFO - PROCESS | 5336 | 07:09:13 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49374/tests/kraken/driver.html?testName=imaging-darkroom) 07:09:14 INFO - PROCESS | 5336 | RSS: Main: 221818880 07:09:14 INFO - PROCESS | 5336 | 07:09:17 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49374/tests/kraken/driver.html?testName=imaging-desaturate) 07:09:18 INFO - PROCESS | 5336 | RSS: Main: 216281088 07:09:18 INFO - PROCESS | 5336 | 07:09:21 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49374/tests/kraken/driver.html?testName=json-parse-financial) 07:09:21 INFO - PROCESS | 5336 | RSS: Main: 217972736 07:09:21 INFO - PROCESS | 5336 | 07:09:23 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49374/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:09:23 INFO - PROCESS | 5336 | RSS: Main: 181579776 07:09:23 INFO - PROCESS | 5336 | 07:09:25 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:09:26 INFO - PROCESS | 5336 | RSS: Main: 188923904 07:09:26 INFO - PROCESS | 5336 | 07:09:28 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:09:29 INFO - PROCESS | 5336 | RSS: Main: 197226496 07:09:29 INFO - PROCESS | 5336 | 07:09:31 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:09:32 INFO - PROCESS | 5336 | RSS: Main: 207093760 07:09:32 INFO - PROCESS | 5336 | 07:09:35 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:09:36 INFO - PROCESS | 5336 | RSS: Main: 202833920 07:09:36 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49374/tests/kraken/driver.html?testName=ai-astar) 07:09:38 INFO - PROCESS | 5336 | RSS: Main: 252452864 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | __start_tp_report 07:09:38 INFO - PROCESS | 5336 | _x_x_mozilla_page_load 07:09:38 INFO - PROCESS | 5336 | _x_x_mozilla_page_load_details 07:09:38 INFO - PROCESS | 5336 | |i|pagename|runs| 07:09:38 INFO - PROCESS | 5336 | |0;ai-astar;105;117;116;115;116;118;116;116;115;114 07:09:38 INFO - PROCESS | 5336 | |1;audio-beat-detection;157;123;128;167;124;123;126;126;143;123 07:09:38 INFO - PROCESS | 5336 | |2;audio-dft;164;162;161;162;163;160;163;160;160;160 07:09:38 INFO - PROCESS | 5336 | |3;audio-fft;75;77;76;115;76;78;76;77;90;76 07:09:38 INFO - PROCESS | 5336 | |4;audio-oscillator;85;85;111;85;84;85;99;86;87;104 07:09:38 INFO - PROCESS | 5336 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;117;117 07:09:38 INFO - PROCESS | 5336 | |6;imaging-darkroom;148;149;149;149;149;149;148;150;149;148 07:09:38 INFO - PROCESS | 5336 | |7;imaging-desaturate;95;95;96;95;95;95;96;96;96;95 07:09:38 INFO - PROCESS | 5336 | |8;json-parse-financial;60;59;60;65;59;60;62;60;60;60 07:09:38 INFO - PROCESS | 5336 | |9;json-stringify-tinderbox;67;67;68;67;68;68;68;68;67;68 07:09:38 INFO - PROCESS | 5336 | |10;stanford-crypto-aes;95;84;94;85;84;85;85;85;84;94 07:09:38 INFO - PROCESS | 5336 | |11;stanford-crypto-ccm;135;141;134;132;132;132;133;134;178;139 07:09:38 INFO - PROCESS | 5336 | |12;stanford-crypto-pbkdf2;172;174;171;171;172;175;173;174;171;174 07:09:38 INFO - PROCESS | 5336 | |13;stanford-crypto-sha256-iterative;70;67;70;67;70;68;67;67;66;68 07:09:38 INFO - PROCESS | 5336 | __end_tp_report 07:09:38 INFO - PROCESS | 5336 | __start_cc_report 07:09:38 INFO - PROCESS | 5336 | _x_x_mozilla_cycle_collect,1838 07:09:38 INFO - PROCESS | 5336 | __end_cc_report 07:09:38 INFO - PROCESS | 5336 | __startTimestamp1484060978307__endTimestamp 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | ------- Summary: start ------- 07:09:38 INFO - PROCESS | 5336 | Number of tests: 14 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#0] ai-astar Cycles:10 Average:114.80 Median:116.00 stddev:3.61 (3.1%) stddev-sans-first:1.17 07:09:38 INFO - PROCESS | 5336 | Values: 105.0 117.0 116.0 115.0 116.0 118.0 116.0 116.0 115.0 114.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:127.00 stddev:16.08 (12.7%) stddev-sans-first:14.74 07:09:38 INFO - PROCESS | 5336 | Values: 157.0 123.0 128.0 167.0 124.0 123.0 126.0 126.0 143.0 123.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.51 (0.9%) stddev-sans-first:1.30 07:09:38 INFO - PROCESS | 5336 | Values: 164.0 162.0 161.0 162.0 163.0 160.0 163.0 160.0 160.0 160.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.50 stddev:12.52 (16.4%) stddev-sans-first:13.05 07:09:38 INFO - PROCESS | 5336 | Values: 75.0 77.0 76.0 115.0 76.0 78.0 76.0 77.0 90.0 76.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:85.00 stddev:9.81 (11.5%) stddev-sans-first:10.16 07:09:38 INFO - PROCESS | 5336 | Values: 85.0 85.0 111.0 85.0 84.0 85.0 99.0 86.0 87.0 104.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:09:38 INFO - PROCESS | 5336 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 07:09:38 INFO - PROCESS | 5336 | Values: 148.0 149.0 149.0 149.0 149.0 149.0 148.0 150.0 149.0 148.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:09:38 INFO - PROCESS | 5336 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:60.00 stddev:1.78 (3.0%) stddev-sans-first:1.88 07:09:38 INFO - PROCESS | 5336 | Values: 60.0 59.0 60.0 65.0 59.0 60.0 62.0 60.0 60.0 60.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 07:09:38 INFO - PROCESS | 5336 | Values: 67.0 67.0 68.0 67.0 68.0 68.0 68.0 68.0 67.0 68.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:85.00 stddev:4.74 (5.6%) stddev-sans-first:4.18 07:09:38 INFO - PROCESS | 5336 | Values: 95.0 84.0 94.0 85.0 84.0 85.0 85.0 85.0 84.0 94.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:134.50 stddev:14.04 (10.4%) stddev-sans-first:14.82 07:09:38 INFO - PROCESS | 5336 | Values: 135.0 141.0 134.0 132.0 132.0 132.0 133.0 134.0 178.0 139.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:173.50 stddev:1.49 (0.9%) stddev-sans-first:1.56 07:09:38 INFO - PROCESS | 5336 | Values: 172.0 174.0 171.0 171.0 172.0 175.0 173.0 174.0 171.0 174.0 07:09:38 INFO - PROCESS | 5336 | 07:09:38 INFO - PROCESS | 5336 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.49 (2.2%) stddev-sans-first:1.39 07:09:38 INFO - PROCESS | 5336 | Values: 70.0 67.0 70.0 67.0 70.0 68.0 67.0 67.0 66.0 68.0 07:09:38 INFO - PROCESS | 5336 | -------- Summary: end -------- 07:09:38 INFO - PROCESS | 5336 | 07:09:39 INFO - TEST-INFO | 5336: exit 0 07:09:39 INFO - TEST-OK | kraken | took 63947ms 07:09:39 INFO - SUITE-END | took 1186s 07:09:39 INFO - Completed test suite (00:19:46) 07:09:39 INFO - javascript benchmark 07:09:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2580.8709564521773, 2597.5973724072774, 2625.973740262598, 2640.353113487196, 2650.2579277802224, 4656.580907718313, 4704.364910737049, 4716.174669432853, 4729.219678753006, 4760.761961901909, 4719.528047195278, 4728.148933192024, 4772.832950846721, 4783.043391321732, 4807.999999999991, 4297.978510107445, 4319.805608747614, 4344.9348259776125, 4391.143726973241, 4463.285874260119, 4056.026553627123, 4386.56134386562, 4431.091626216634, 4451.554844515555, 4505.07645932583, 4265.573442655741, 4306.418633484503, 4381.649468042581, 4390.912181756347, 4471.575200355961, 4328.61042506176, 4339.0, 4386.649068074546, 4404.273294906337, 4468.106378724268, 4374.934375984363, 4376.190404775095, 4400.339949007643, 4415.4259946206785, 4431.667624928144, 4733.408323959505, 4742.644301677354, 4760.0479904018985, 4777.856664300077, 4817.590504807061, 4999.075171093323, 5044.2937988681615, 5055.772490237948, 5109.795608175668, 5116.744162791882, 5055.873603159914, 5113.411957624883, 5122.410922743895, 5127.8205262816, 5128.205128205134, 4781.402324709411, 4806.519348065235, 4832.44426890912, 4870.805167793319, 4877.0, 5216.26972223889, 5218.947810521846, 5236.92144617831, 5243.161094224905, 5276.709780962084, 4883.658143929962, 4913.115639184981, 4981.875453113628, 5018.272350509229, 5037.571806396424, 4953.281774142729, 4959.900801984012, 5050.267711181858, 5096.9490305097215, 5104.03023425548, 5003.774830132653, 5006.048850718352, 5009.674371165863, 5010.148274793294, 5028.949710502849, 5009.674371165863, 5028.069807085701, 5030.4466508684745, 5068.467810879878, 5125.384953805567, 4258.254805409041, 4294.377315289328, 4303.440552728125, 4304.3543468479975, 4339.783010849508, 3675.6324367563566, 3739.8878033659034, 3776.660100590905, 3799.5440547133967, 3829.7319187657154, 4333.71830830995, 4352.564743525685, 4373.671974601918, 4387.14450682114, 4452.888677783081, 5125.051865404912, 5126.282320475137, 5126.384833819965, 5126.846194614168, 5178.300929374486, 4404.031113155165, 4410.0738844842945, 4413.337999300131, 4413.933790993137, 4456.064226512469, 4449.822007119679, 4498.932516012263, 4498.942748454111, 4540.682152249311, 4541.568550987651, 4775.426948766031, 4779.761011949458, 4783.449903261169, 4793.089313030513, 4824.517548245148, 5087.720175390241, 5105.566026887756, 5107.310513080686, 5107.336046313955, 5108.386993560796, 4696.976515117402, 4725.787339569796, 4727.456342520517, 4784.832530861403, 4788.209945358977, 4959.97520012412, 4986.551210391082, 5018.1469150245275, 5018.824341148043, 5020.397552293748, 5032.370953630796, 5062.443131255485, 5066.214736715815, 5095.0574143783515, 5097.515736005073, 4954.232094025432, 4966.329545511309, 4966.900661986812, 4972.651914366104, 5021.874453138701, 4956.083124621957, 4960.553550180501, 4961.8511444656715, 4964.255361695775, 5010.098182327216, 4756.6908150970075, 4844.176489996779, 4852.102361063215, 4871.318015477907, 4877.609791216765, 4958.330625365529, 4999.0, 5003.524665156804, 5006.299118123538, 5039.697618142924, 4867.123917694707, 4869.537393947569, 4869.70781753096, 4876.049170411878, 4906.018796240837], "value": 4662.708801024891, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.8496820890151, 546.2680008788222, 553.7037684838613, 562.4881357964254, 563.399979022341, 31873.840630796843, 32731.345373092525, 34391.65608343916, 34486.65513344866, 37246.0, 35716.2856742865, 36109.81945090271, 36128.638713612854, 36202.63797362026, 36206.818965905135, 7666.8849967250535, 7848.175941526115, 7854.253845884632, 7858.29275365217, 7890.921090789076, 7603.0496187976505, 7794.766157015299, 9967.601295948154, 10225.642102526414, 10257.281990260684, 9819.705408837746, 9878.407295562289, 9883.406995580288, 9889.406635601887, 9905.158061564776, 10296.588136474533, 10349.482525873751, 10351.844722329171, 10356.637517686844, 10372.896271037269, 9782.461964591945, 9833.213342932548, 9859.950700246525, 9860.506974651238, 9861.852072218922, 39249.41125883114, 39512.0, 39592.80203598963, 39618.603813962065, 39683.20633587312, 3376.6623337666283, 3438.2607739336163, 3465.740069494773, 3477.739169562293, 3477.8434970426392, 29730.851345743133, 29936.90441669084, 30329.24176895595, 30389.544156837666, 30502.54246186309, 3147.35479226758, 3161.351922855809, 3226.3386005868742, 3246.480563109891, 3332.9833350833333, 243370.0, 243692.0, 244472.0, 244736.0, 245251.0, 54716.45283547114, 54864.177037344474, 54897.725511372984, 55027.44972550304, 55044.44955550473, 58879.41120588825, 58986.70506647525, 59078.70460647669, 59097.0, 59104.704476477345, 13200.14199077057, 13697.356317241994, 13701.314934253447, 13714.519991800438, 13741.312934353242, 8613.181747733955, 8877.911220887838, 10257.948710256402, 10363.585456581803, 10368.118709909591, 15058.774118388234, 15165.317560709795, 15224.553667401578, 16722.581935451468, 16959.0, 6476.158099447041, 6572.638504882278, 6696.296888826603, 6726.981905428352, 6741.59550426976, 816.5957850863861, 833.8492879825801, 846.0397448895425, 856.4347530629755, 866.956652167389, 27225.727742722716, 27583.31041723973, 28026.29934251619, 28689.28276793097, 28697.426051478855, 886.0873300500494, 973.5570315506427, 985.5762022330323, 985.9260555458369, 994.6916455898695, 14749.631259218606, 14878.256087195598, 15153.621159471102, 15285.565733107152, 15305.387784488496, 339659.0, 343368.0, 343872.0, 346583.0, 347278.0, 52409.47590524122, 52674.0, 52914.0, 52923.47076529262, 53005.0, 1240.2124650846597, 1267.6830792301926, 1281.9294938778457, 1287.5557932513454, 1293.6959814443599, 25540.0, 26417.867910661094, 26520.602190966765, 27612.0, 27623.171304860887, 42352.364714529926, 43048.35427468653, 43066.354004690584, 43086.35370469508, 43141.784291078344, 189432.0, 190339.0, 191443.0, 191692.0, 192288.7711228866, 36081.45877811783, 36118.27763444769, 36189.457158143174, 36551.63448365482, 36578.634213657526, 40488.0, 41022.58977410307, 41309.58690413057, 42068.0, 42105.36841947312, 33812.3237535243, 35190.648093518736, 35474.46788298126, 35629.0, 35647.0, 34442.48336275008, 34485.655143448246, 34544.654553454144, 34701.65298347085, 34736.478952815225, 33341.0, 34029.31941361109, 34041.82979085089, 34133.0, 34210.0, 28156.24250179983, 28573.714262857105, 31531.68468315379, 31802.681973180897, 31923.521147182346, 22799.730356682147, 23057.884710576338, 23321.416964576023, 23465.88267058654, 23558.0], "value": 17490.190490425994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.8160156718507, 357.46915829992463, 360.3333832410042, 365.4609451059689, 390.37150188197, 13578.728425431485, 17125.143742812856, 17577.1211439428, 18349.44951651449, 18539.35112271071, 16562.917185414055, 17083.829161708378, 17308.0, 17382.304707811705, 17424.82575174248, 4831.130396528614, 5234.528892399683, 5260.316158899338, 5268.973655131719, 5269.76286067126, 3921.2549615573093, 4108.506979162505, 5189.636725429222, 5294.232336311233, 5298.973505132469, 5819.854503637422, 6081.665508397036, 6103.45068943795, 6208.0, 6222.9688851555675, 6060.666663333515, 6185.226846644169, 6197.504199664038, 6241.469475094623, 6292.653904035276, 5510.531604813596, 5525.226468294442, 5544.722763861791, 5548.223248745179, 5556.888862222773, 21280.255191068394, 21518.246861359778, 21658.675119873216, 21753.782462175335, 21836.563268734695, 2536.746325367467, 2566.0762125634756, 2593.7017243016917, 2609.308533238693, 2641.2340421277804, 13624.523141690092, 13854.238016909067, 13939.930300348433, 14015.50945716895, 14333.641658958506, 1763.3916298876868, 1817.4547635709234, 1850.1951651031845, 1851.1392202625843, 1932.5651728361147, 79350.0, 80177.9002380984, 80892.19107808906, 81200.59399703023, 83672.16327836766, 10415.323004004715, 10644.201684873666, 10780.353178809297, 10781.407022613676, 10814.459277036116, 14739.92630036843, 14764.557063288119, 14774.335154917839, 14780.260986950609, 14806.851931480547, 6661.633610151488, 6813.625250611165, 6933.5493192942295, 6951.756688515878, 6980.162380514371, 6080.331163572004, 6154.476869466056, 6288.8427789304715, 6320.968395158086, 6334.493240540745, 4410.117976404667, 4527.818887244473, 4528.6150677192145, 4627.953720462752, 4629.8148074077335, 1845.7462173657655, 1869.9626007479774, 1884.5100273928604, 1884.8825438933088, 1920.9135588898532, 908.7909780750465, 911.0980129671577, 938.202527851321, 944.7354740672486, 968.7917097823861, 16596.0, 16809.0, 16849.831501685072, 17723.11384430799, 17838.643227135384, 912.7079334612993, 916.2807196350833, 918.1828172926101, 928.7863791327898, 933.0, 9291.535423228812, 9480.194183494343, 9690.321677482509, 9697.951510242403, 9815.067568580973, 451073.0, 454271.0, 458847.0, 465037.0, 465894.0, 87869.0, 88929.0, 89910.0, 91848.54075729579, 91930.0, 913.0371943044729, 918.3020904112789, 944.2870632672289, 944.8441007233866, 947.3605316411395, 14527.201003944885, 14544.0, 14691.191984440959, 14983.40066397332, 15472.458463953708, 38414.62195292717, 39065.28470583568, 39098.2180356397, 39263.607363927134, 39744.40383394309, 52495.21257181222, 52736.47263527316, 53573.0, 55249.1712624319, 55829.72085139548, 7615.010048693634, 7649.349805266615, 7671.769846904602, 7673.462857600138, 7838.490498117604, 8790.604422800861, 11653.0, 11728.179027468337, 11797.35114568707, 11863.822042669195, 9157.816843663222, 9561.952190239004, 9638.9036109638, 10400.0, 10422.270441069355, 10504.632337868137, 10600.522976465923, 10629.202809789142, 10714.303570267908, 11057.889421106007, 9962.651307204213, 10162.491875406347, 10451.947740261554, 10525.421101839473, 10809.891901081202, 7802.804929876572, 7827.0, 7955.4828936118965, 8283.37874659415, 8286.337093032422, 9531.761705957406, 9996.500174991075, 10025.799484010426, 10035.949820250851, 10140.898591014291], "value": 8621.394765273451, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.6644322141076, 1382.854800245974, 1383.5295999360214, 1388.2087210290138, 1401.572520381284, 6949.965250173748, 7367.447441441893, 7381.778546643596, 7389.150247721514, 7390.704371825121, 6362.713677884495, 6521.869562608745, 7010.964945175267, 7167.713291468335, 7222.674979625916, 2171.9782802172012, 2207.293666026872, 2210.900509477069, 2213.1258153029544, 2216.92240771573, 2105.26315789474, 2187.5187458759046, 2199.25225423356, 2209.7790220977936, 2209.790069943361, 1893.8863668179883, 1964.9705254421194, 2088.6866969954553, 2135.996081841532, 2151.075037733774, 2150.827933765295, 2158.1583182558816, 2164.588728141648, 2178.6731990201597, 2183.355910006544, 2159.4169574214875, 2169.4901698100934, 2176.5973294940486, 2177.444751588335, 2178.2593918067773, 7121.359077683033, 7152.9642351788425, 7192.100987376578, 7207.49547531673, 7212.386947109502, 4336.284513055373, 4468.910621787546, 4470.441194850644, 4491.1466821304175, 4495.865124046284, 2753.9810270200155, 2811.831290122599, 2865.885364585414, 2866.4123854609757, 2866.484032874082, 1055.757175849559, 1121.669107613256, 1129.9604513841975, 1136.102479041556, 1162.7790719763302, 9688.370255933341, 9693.642889995406, 9696.369735967139, 9699.563519641562, 9702.805943881083, 3968.3849003404516, 4125.1749650070105, 4141.5237247716295, 4185.267578173807, 4210.915781684349, 7715.961420192863, 7762.456628035983, 7768.572728499987, 7771.10632277284, 7772.417068719868, 4410.889727756832, 4445.777711114496, 4447.3329000650165, 4449.710768800018, 4451.977740111279, 3857.884263472044, 3969.4641223435046, 4002.739821911625, 4151.12826306473, 4151.501819781645, 3246.5130230465147, 3331.566896303465, 3349.3468773588916, 3353.714934230569, 3373.3421982713135, 1024.784795193003, 1029.264076185531, 1031.7626945802508, 1032.5301987595626, 1041.1358572385054, 648.1088503307951, 648.8385789436855, 654.2443477783168, 669.0399277037467, 688.7761477519826, 4828.517148285144, 4885.0, 4983.152864013126, 5025.723585202776, 5116.641835071584, 919.4069824962861, 946.4841661294611, 955.1212884146602, 967.8790151231096, 968.3899143539525, 3218.1311046017913, 3228.2090887732656, 3278.983605082007, 3311.6357200707903, 3328.4674452088034, 8325.292350150305, 8790.956045219733, 8803.295736341077, 8807.119288071142, 8828.823423531494, 8743.38796284254, 8768.561571921378, 8775.868361974575, 8779.341549383822, 8781.956090219635, 6842.794716158523, 6933.757318493828, 6934.098567186234, 6935.3411425914455, 6939.375456208965, 4665.720056796603, 4769.141554520081, 4790.473047964651, 4820.276958456119, 4852.927206091841, 7780.92219077802, 7948.16544262844, 8005.599720014093, 8018.719344822904, 8029.237222463857, 8123.228293312126, 8131.552764598004, 8205.097439281555, 8218.712345067894, 8355.874661880198, 6141.170941922962, 6252.249730032425, 6281.434670879643, 6293.842653933688, 6294.622322660655, 6566.24488183865, 6732.192136943598, 6741.797746067626, 6786.59280443175, 6787.321267873371, 6213.2233470816145, 6271.404216599416, 6276.340984196776, 6278.968605156945, 6362.268339141058, 6145.231846019247, 6218.9378106220165, 6234.9688251558455, 6270.529710271657, 6271.4669253114, 5418.972905135449, 5541.833744987657, 5589.860253493533, 5597.608167428241, 5642.379338272869, 4095.4675892135024, 4099.307159353359, 4119.629233369011, 4152.4601801765575, 4234.237837189212, 4188.560201178954, 4255.744655320566, 4320.848770293024, 4328.848490302824, 4406.823727050882], "value": 3888.9746298305436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.37636319170286, 355.46502513716854, 356.54362416107375, 358.63598447575697, 366.71213097718294, 4888.437829649592, 4888.902221955559, 4915.893923867133, 4922.581580565652, 4929.753512324383, 4821.807127714887, 4823.589994850442, 4834.4923783002805, 4843.39457567804, 4861.7812198451065, 3692.335379631665, 3804.5434547854297, 3808.1812410331772, 3817.866374676887, 3896.629820167094, 3888.411674224414, 3891.221755648867, 3897.9025524361978, 3898.1813819097883, 3911.5892831252736, 3877.1857909838827, 3887.378019516878, 3896.2597106549665, 3918.490596222487, 3923.6664883484937, 3732.757370770905, 3772.603876592973, 3842.675181515171, 3863.16941857502, 3940.4483372327754, 3499.597546282185, 3506.947395789065, 3509.9122521937156, 3513.5959364673135, 3515.209077957439, 4708.223143181371, 4722.433308003027, 4729.905401891943, 4731.881702957454, 4799.2801079837955, 4302.569743025703, 4466.955330446686, 4484.080763443486, 4489.03485750562, 4503.864884053483, 5053.84838454847, 5077.517635824628, 5117.385913690381, 5138.794448222066, 5138.845834624967, 3874.6900247980093, 3887.086534664352, 3893.5911729268587, 3896.123372241251, 3903.6877049835944, 5084.44071152173, 5320.334958130234, 5326.387465441447, 5350.438203988603, 5386.5994841341535, 3769.7738135711943, 3813.237352529505, 3825.215830754689, 3829.942550861739, 3835.3096442640594, 3895.5130608673917, 3897.863574774926, 3901.122247494348, 3903.2193561287854, 3905.1799122184093, 4378.43080399546, 4394.143142087262, 4403.295472724413, 4403.647708183402, 4406.735595864258, 4995.775190116454, 5093.573799336192, 5105.264210168561, 5111.233315002779, 5142.717150556755, 1340.5777180188209, 1341.4164838295374, 1345.7282867690135, 1347.6294019144814, 1349.190485708567, 904.5929331800716, 962.6979361542637, 985.3250523391499, 994.6071301835879, 1003.0420947994647, 4256.574342565718, 4392.472903251631, 4485.887852803706, 4507.2112380333965, 4517.841875534341, 5261.605379596545, 5263.210508427153, 5263.447338029452, 5269.811763882873, 5275.182346736262, 4370.147821174841, 4499.212637788374, 4501.392312037833, 4512.142692888407, 4525.0, 2669.826461280011, 2682.5573780326026, 2688.6639170103736, 2698.406350602864, 2699.5275826730635, 4759.333693282943, 4766.666333356632, 4781.95218047822, 4783.7129772213775, 4792.808287668524, 4999.525045120708, 5190.8183213587345, 5205.323307970016, 5214.45248248936, 5226.686398816083, 4728.682945356751, 4732.384789977281, 4754.405699287589, 4764.571188593043, 4780.330753694486, 5074.873128171677, 5080.364954380702, 5088.363954505687, 5108.821191258288, 5115.56517696, 4748.928766068442, 4937.28409380645, 4988.7777494514075, 4989.027139707868, 5009.649324547392, 4712.835050773207, 4732.119661877227, 4740.976295118502, 4742.596879265163, 4792.209285467999, 3766.0, 3769.9444155636465, 3849.82675779585, 3854.4989151410136, 3854.903627409225, 4943.283223932438, 5095.312132862016, 5105.668131571436, 5113.130767769562, 5120.334356533627, 5083.237514372874, 5086.516780905808, 5096.770645320896, 5107.284980102714, 5137.8715532112, 5049.621278404208, 5069.7211653357945, 5091.159958606937, 5099.796008159632, 5107.259447380184, 4822.397200349957, 5006.220303670705, 5007.499250074963, 5010.448850626355, 5038.521340472649, 4885.316055752269, 4928.8767780805765, 5005.1241032819835, 5087.23691446286, 5108.28484012246, 4989.675671081368, 4991.482708149131, 4995.400551933792, 4995.825146119869, 5013.273075404119], "value": 3879.3011051692624, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.9228438729541, 691.3466773898667, 738.9298016688416, 771.4137255685678, 792.1761368177098, 12767.553135640255, 13953.651158721037, 13977.161370317788, 14070.57788266351, 14085.295735213236, 13604.795928061087, 13801.792973105412, 13802.102863313903, 13815.930920345385, 13816.861831381682, 658.9967050164743, 674.6154691825661, 746.4998451037818, 764.5183534373336, 798.7843282313772, 807.4428644235488, 809.2231457800518, 815.1115284340068, 816.4937738602075, 816.8652172391578, 863.2489733931488, 863.8574635185187, 864.5893200729635, 865.2645251536206, 865.6580650642992, 693.6254422611784, 713.8286811165283, 859.5100792548219, 859.6002858670716, 859.9441036332619, 526.5445389737887, 526.6497778977014, 527.4435470578538, 604.0757640809568, 619.2383368456823, 13251.204927704272, 13304.534341298007, 13348.33258337089, 13382.464701411931, 13412.530561430303, 12816.231026138461, 12846.935765321206, 13147.86852131476, 13149.934250328783, 13184.274864882427, 10854.34873907568, 10876.673699789018, 10983.286086404358, 10996.28524145928, 11072.335659860435, 4091.161311931047, 4099.077707515815, 4104.692148088905, 4118.814653340607, 4164.3961625564425, 61376.0, 64404.677976610285, 64866.3513364865, 66585.99999999952, 66857.66571167113, 10309.690709278733, 10391.948040259751, 10500.894991050145, 10666.78666426667, 10816.72958176036, 20222.19111235534, 20321.18715251403, 20433.080511377022, 20561.588768224552, 20626.58746825085, 6272.2159730033745, 6312.147860038836, 6430.131932189188, 6485.189351331083, 6564.67176641166, 3359.3617212729505, 3362.2939182771893, 3375.814330211862, 3417.79493230407, 3428.177237462991, 2842.4173044525824, 2918.343372741116, 2941.1323659520494, 2942.190897503161, 2951.8081324713826, 500.7721486723532, 503.2375950435094, 504.08456759922285, 507.9339685840817, 509.5083244668912, 478.78693981178736, 497.1895809830026, 504.48290502234767, 515.8288161184084, 523.6674711558188, 7599.138211138306, 7684.154742978268, 8061.717839875693, 8138.186181381814, 8298.792530186794, 489.68415372084803, 490.208190232403, 498.9176785830297, 544.8120398462524, 547.7891011960344, 3593.299306635181, 3609.5848977367414, 3614.253506514242, 3698.68561172298, 3718.925621487609, 51345.743271283405, 54354.184687229725, 56214.156787648215, 56752.43247567554, 61529.69235153795, 47553.286700699515, 56413.71793141008, 56652.58368540819, 58095.419045809846, 59985.400145997985, 47904.04191916142, 49400.25899611437, 52753.736231319366, 52795.47204527982, 52896.20655690168, 8378.204070613281, 8843.955780221058, 9185.724428267162, 9339.299552533452, 9413.905860941302, 36602.633973659926, 38346.42480362853, 38994.805025975824, 40129.1974160521, 40156.79921600373, 46755.53244467512, 48424.27363589479, 48806.511934880196, 50371.74814125906, 50479.49520504748, 11571.92229769977, 11583.76832463363, 11655.30068195911, 11875.109366797353, 12323.198992065487, 11919.0, 11993.64019079429, 12250.693732656755, 12741.235525868478, 12986.0, 11921.344326061802, 11927.76144477123, 12582.748345033231, 12731.681707957005, 12746.553870614483, 10621.575136994434, 10728.89271107279, 10860.728481788019, 11230.04544613717, 11233.55065797359, 11291.43542822872, 11437.370944598128, 11530.481128349413, 11667.824982625098, 12320.81518777237, 9924.255680823822, 9926.652567160114, 10112.646057387956, 10245.231607629308, 10539.15686745074, 9647.807043859224, 10215.0, 10303.53634086453, 10400.323978941344, 10439.739006524898], "value": 6287.300509494287, "unit": "score"}], "name": "dromaeo_css", "value": 6368.236330086408, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 116.0, 115.0, 116.0, 118.0, 116.0, 116.0, 115.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 128.0, 167.0, 124.0, 123.0, 126.0, 126.0, 143.0, 123.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 161.0, 162.0, 163.0, 160.0, 163.0, 160.0, 160.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 115.0, 76.0, 78.0, 76.0, 77.0, 90.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 111.0, 85.0, 84.0, 85.0, 99.0, 86.0, 87.0, 104.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 150.0, 149.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 65.0, 59.0, 60.0, 62.0, 60.0, 60.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 68.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 84.0, 94.0, 85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 94.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 141.0, 134.0, 132.0, 132.0, 132.0, 133.0, 134.0, 178.0, 139.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 171.0, 171.0, 172.0, 175.0, 173.0, 174.0, 171.0, 174.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 67.0, 70.0, 68.0, 67.0, 67.0, 66.0, 68.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1539.3, "alertThreshold": 2.0}]} 07:09:40 INFO - Return code: 0 07:09:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:09:40 INFO - # TBPL SUCCESS # 07:09:40 INFO - Running post-action listener: _resource_record_post_action 07:09:40 INFO - [mozharness: 2017-01-10 15:09:40.103629Z] Finished run-tests step (success) 07:09:40 INFO - Running post-run listener: _resource_record_post_run 07:09:40 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396664832; Read time: 12; Write time: 345240 07:09:40 INFO - TinderboxPrint: CPU usage
13.1% 07:09:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:09:40 INFO - TinderboxPrint: I/O write bytes / time
396,664,832 / 345,240 07:09:40 INFO - TinderboxPrint: CPU idle
8,349.5 (86.9%) 07:09:40 INFO - TinderboxPrint: CPU user
1,212.9 (12.6%) 07:09:40 INFO - TinderboxPrint: Swap in / out
0 / 0 07:09:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110841856; Read time: 0; Write time: 90388 07:09:40 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 285208576; Read time: 0; Write time: 254852 07:09:40 INFO - Running post-run listener: _upload_blobber_files 07:09:40 INFO - Blob upload gear active. 07:09:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:09:40 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 07:09:40 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:09:40 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:09:41 INFO - (blobuploader) - INFO - Open directory for files ... 07:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:42 INFO - (blobuploader) - INFO - Done attempting. 07:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:09:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:43 INFO - (blobuploader) - INFO - Done attempting. 07:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:46 INFO - (blobuploader) - INFO - Done attempting. 07:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:09:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:47 INFO - (blobuploader) - INFO - Done attempting. 07:09:47 INFO - (blobuploader) - INFO - Iteration through files over. 07:09:47 INFO - Return code: 0 07:09:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:09:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:09:47 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8ee949d792162cf98c1f02d268bd2d44510485535df0da583eda65659ef0d4b6bdc821c7acfbdfc7a4be9d8dc5302e625fc10e571500b03902e2047b1e609531", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de69285945b30a175cc1749400ef947d602d2b828130e31ffaf35e22af14d7edfff6ec18394c99d6135902d6703d142d19587ec742568cff1e65a5cbef7d5a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c874f529feb5ff352be9ff0b738e1162ca3b4b9ffbdeb53c81b4fe1df6df256cee0612beea92581725a9b3c9a1990531eca69a6ef14225640ce85646846f07f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a5fb4edf09ec955456e45536b5d9c571a6ee71f5191097b1990f33fb7545d11ba7cf859e92c07eefd4dde192eaec3a1864e178b0bdb0b1a129b0f01854b5178a"} 07:09:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:09:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:09:47 INFO - Contents: 07:09:47 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8ee949d792162cf98c1f02d268bd2d44510485535df0da583eda65659ef0d4b6bdc821c7acfbdfc7a4be9d8dc5302e625fc10e571500b03902e2047b1e609531", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de69285945b30a175cc1749400ef947d602d2b828130e31ffaf35e22af14d7edfff6ec18394c99d6135902d6703d142d19587ec742568cff1e65a5cbef7d5a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c874f529feb5ff352be9ff0b738e1162ca3b4b9ffbdeb53c81b4fe1df6df256cee0612beea92581725a9b3c9a1990531eca69a6ef14225640ce85646846f07f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a5fb4edf09ec955456e45536b5d9c571a6ee71f5191097b1990f33fb7545d11ba7cf859e92c07eefd4dde192eaec3a1864e178b0bdb0b1a129b0f01854b5178a"} 07:09:47 INFO - Running post-run listener: copy_logs_to_upload_dir 07:09:47 INFO - Copying logs to upload dir... 07:09:47 INFO - mkdir: /builds/slave/test/build/upload/logs 07:09:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.764009 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-10 07:09:47.084367) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 07:09:47.087414) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8ee949d792162cf98c1f02d268bd2d44510485535df0da583eda65659ef0d4b6bdc821c7acfbdfc7a4be9d8dc5302e625fc10e571500b03902e2047b1e609531", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de69285945b30a175cc1749400ef947d602d2b828130e31ffaf35e22af14d7edfff6ec18394c99d6135902d6703d142d19587ec742568cff1e65a5cbef7d5a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c874f529feb5ff352be9ff0b738e1162ca3b4b9ffbdeb53c81b4fe1df6df256cee0612beea92581725a9b3c9a1990531eca69a6ef14225640ce85646846f07f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a5fb4edf09ec955456e45536b5d9c571a6ee71f5191097b1990f33fb7545d11ba7cf859e92c07eefd4dde192eaec3a1864e178b0bdb0b1a129b0f01854b5178a"} build_url:https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011877 build_url: 'https://queue.taskcluster.net/v1/task/Pdfufw9bSc-RidALSAjUBQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8ee949d792162cf98c1f02d268bd2d44510485535df0da583eda65659ef0d4b6bdc821c7acfbdfc7a4be9d8dc5302e625fc10e571500b03902e2047b1e609531", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de69285945b30a175cc1749400ef947d602d2b828130e31ffaf35e22af14d7edfff6ec18394c99d6135902d6703d142d19587ec742568cff1e65a5cbef7d5a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c874f529feb5ff352be9ff0b738e1162ca3b4b9ffbdeb53c81b4fe1df6df256cee0612beea92581725a9b3c9a1990531eca69a6ef14225640ce85646846f07f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a5fb4edf09ec955456e45536b5d9c571a6ee71f5191097b1990f33fb7545d11ba7cf859e92c07eefd4dde192eaec3a1864e178b0bdb0b1a129b0f01854b5178a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 07:09:47.112968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 07:09:47.113301) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1484059593.44001-1483665944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004415 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 07:09:47.217956) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 07:09:47.218274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 07:09:47.218554) ========= ========= Total master_lag: 0.17 =========