builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1489582499.96 results: success (0) revision: 0cc9dced786cf2a3baeff707b636b4ad02637df0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 05:54:59.959545) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 05:54:59.959985) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 05:54:59.960256) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 05:54:59.974661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:54:59.974991) ========= 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=3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-15 05:55:00-- 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% 224M=0s 2017-03-15 05:55:00 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.197098 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:00.185632) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:00.185963) ========= 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=3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017046 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:00.235469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-15 05:55:00.235834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0cc9dced786cf2a3baeff707b636b4ad02637df0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 0cc9dced786cf2a3baeff707b636b4ad02637df0 --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=3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-15 05:55:01,070 truncating revision to first 12 chars 2017-03-15 05:55:01,070 Setting DEBUG logging. 2017-03-15 05:55:01,070 attempt 1/10 2017-03-15 05:55:01,070 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0cc9dced786c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-15 05:55:02,006 unpacking tar archive at: mozilla-central-0cc9dced786c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.213503 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-15 05:55:01.462536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:01.462865) ========= script_repo_revision: 0cc9dced786cf2a3baeff707b636b4ad02637df0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:01.463278) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:01.463556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 05:55:01.471633) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-15 05:55:01.471911) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:55:02 INFO - MultiFileLogger online at 20170315 05:55:02 in /builds/slave/test 05:55:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:55:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:55:02 INFO - {'append_to_log': False, 05:55:02 INFO - 'base_work_dir': '/builds/slave/test', 05:55:02 INFO - 'blob_upload_branch': 'Firefox', 05:55:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:55:02 INFO - 'branch': 'Firefox', 05:55:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:55:02 INFO - 'config_files': ('talos/linux_config.py',), 05:55:02 INFO - 'default_actions': ('clobber', 05:55:02 INFO - 'read-buildbot-config', 05:55:02 INFO - 'download-and-extract', 05:55:02 INFO - 'populate-webroot', 05:55:02 INFO - 'create-virtualenv', 05:55:02 INFO - 'install', 05:55:02 INFO - 'run-tests'), 05:55:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:55:02 INFO - 'download_minidump_stackwalk': True, 05:55:02 INFO - 'download_symbols': 'ondemand', 05:55:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:55:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:55:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:55:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:55:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:55:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:55:02 INFO - 'gecko_profile': False, 05:55:02 INFO - 'gecko_profile_interval': 0, 05:55:02 INFO - 'installer_path': 'installer.exe', 05:55:02 INFO - 'log_level': 'info', 05:55:02 INFO - 'log_name': 'talos', 05:55:02 INFO - 'log_to_console': True, 05:55:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:55:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:55:02 INFO - 'opt_config_files': (), 05:55:02 INFO - 'pip_index': False, 05:55:02 INFO - 'suite': 'dromaeojs', 05:55:02 INFO - 'system_bits': '32', 05:55:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:55:02 INFO - 'title': 'talos-linux64-ix-059', 05:55:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:55:02 INFO - 'use_talos_json': True, 05:55:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:55:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:55:02 INFO - 'work_dir': 'build'} 05:55:02 INFO - [mozharness: 2017-03-15 12:55:02.411909Z] Running clobber step. 05:55:02 INFO - Running pre-action listener: _resource_record_pre_action 05:55:02 INFO - Running main action method: clobber 05:55:02 INFO - rmtree: /builds/slave/test/build 05:55:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:55:03 INFO - Running post-action listener: _resource_record_post_action 05:55:03 INFO - [mozharness: 2017-03-15 12:55:03.409349Z] Finished clobber step (success) 05:55:03 INFO - [mozharness: 2017-03-15 12:55:03.409451Z] Running read-buildbot-config step. 05:55:03 INFO - Running pre-action listener: _resource_record_pre_action 05:55:03 INFO - Running main action method: read_buildbot_config 05:55:03 INFO - Using buildbot properties: 05:55:03 INFO - { 05:55:03 INFO - "project": "", 05:55:03 INFO - "product": "firefox", 05:55:03 INFO - "who": "cbook@mozilla.com", 05:55:03 INFO - "installer_path": "public/build/target.tar.bz2", 05:55:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:55:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:55:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:55:03 INFO - "stage_platform": "linux64-pgo", 05:55:03 INFO - "basedir": "/builds/slave/test", 05:55:03 INFO - "buildnumber": 89, 05:55:03 INFO - "platform": "ubuntu64_hw", 05:55:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:55:03 INFO - "slavebuilddir": "test", 05:55:03 INFO - "taskId": "MKtT_CSSTN6ms8KwX_0zHw", 05:55:03 INFO - "branch": "mozilla-central", 05:55:03 INFO - "script_repo_revision": "production", 05:55:03 INFO - "revision": "0cc9dced786cf2a3baeff707b636b4ad02637df0", 05:55:03 INFO - "slavename": "talos-linux64-ix-059", 05:55:03 INFO - "repo_path": "mozilla-central" 05:55:03 INFO - } 05:55:03 INFO - Finding installer, test and symbols from parent task. 05:55:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MKtT_CSSTN6ms8KwX_0zHw'}, attempt #1 05:55:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MKtT_CSSTN6ms8KwX_0zHw'}, attempt #1 05:55:04 INFO - Task dependencies: ZigqRZ3ATR23emKsI6SIUg 05:55:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg'}, attempt #1 05:55:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2 05:55:04 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/test_packages.json 05:55:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.crashreporter-symbols.zip 05:55:04 INFO - Running post-action listener: _resource_record_post_action 05:55:04 INFO - [mozharness: 2017-03-15 12:55:04.811022Z] Finished read-buildbot-config step (success) 05:55:04 INFO - [mozharness: 2017-03-15 12:55:04.811239Z] Running download-and-extract step. 05:55:04 INFO - Running pre-action listener: _resource_record_pre_action 05:55:04 INFO - Running main action method: download_and_extract 05:55:04 INFO - mkdir: /builds/slave/test/build/tests 05:55:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:04 INFO - trying https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.test_packages.json 05:55:04 INFO - Downloading https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:55:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:55:05 INFO - Downloaded 896 bytes. 05:55:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:55:05 INFO - Using the following test package requirements: 05:55:05 INFO - {u'common': [u'target.common.tests.zip'], 05:55:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:55:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:55:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:55:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:55:05 INFO - u'mozbase': [u'target.common.tests.zip'], 05:55:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:55:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:55:05 INFO - u'web-platform': [u'target.common.tests.zip', 05:55:05 INFO - u'target.web-platform.tests.zip'], 05:55:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:55:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:55:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.common.tests.zip 05:55:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:55:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.common.tests.zip into memory 05:55:06 INFO - Expected file size: 60311023 05:55:06 INFO - Obtained file size: 60311023 05:55:16 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:55:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.common.tests.zip 05:55:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:55:16 INFO - Fetch https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.common.tests.zip into memory 05:55:17 INFO - Expected file size: 60311023 05:55:17 INFO - Obtained file size: 60311023 05:55:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.talos.tests.zip 05:55:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:55:28 INFO - Fetch https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.talos.tests.zip into memory 05:55:28 INFO - Expected file size: 14404452 05:55:28 INFO - Obtained file size: 14404452 05:55:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:29 INFO - trying https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2 05:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:55:30 INFO - Downloaded 67049439 bytes. 05:55:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2 05:55:30 INFO - mkdir: /builds/slave/test/properties 05:55:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:55:30 INFO - Writing to file /builds/slave/test/properties/build_url 05:55:30 INFO - Contents: 05:55:30 INFO - build_url:https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2 05:55:30 INFO - Running post-action listener: _resource_record_post_action 05:55:30 INFO - Running post-action listener: set_extra_try_arguments 05:55:30 INFO - [mozharness: 2017-03-15 12:55:30.395066Z] Finished download-and-extract step (success) 05:55:30 INFO - [mozharness: 2017-03-15 12:55:30.395162Z] Running populate-webroot step. 05:55:30 INFO - Running pre-action listener: _resource_record_pre_action 05:55:30 INFO - Running main action method: populate_webroot 05:55:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:55:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:55:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:55:30 INFO - '--tppagecycles', 05:55:30 INFO - '7'], 05:55:30 INFO - 'tests': ['tsvgm']}}, 05:55:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:55:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:55:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:55:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:55:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:55:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:55:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['damp', 'tps']}, 05:55:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'tests': ['damp', 'tps']}, 05:55:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['dromaeo_dom']}, 05:55:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:55:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['basic_compositor_video']}, 05:55:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:55:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['a11yr', 05:55:30 INFO - 'ts_paint', 05:55:30 INFO - 'tpaint', 05:55:30 INFO - 'sessionrestore', 05:55:30 INFO - 'sessionrestore_no_auto_restore', 05:55:30 INFO - 'tabpaint']}, 05:55:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:55:30 INFO - 'ts_paint', 05:55:30 INFO - 'tpaint', 05:55:30 INFO - 'sessionrestore', 05:55:30 INFO - 'sessionrestore_no_auto_restore', 05:55:30 INFO - 'tabpaint']}, 05:55:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:55:30 INFO - 'ts_paint', 05:55:30 INFO - 'tpaint', 05:55:30 INFO - 'sessionrestore', 05:55:30 INFO - 'sessionrestore_no_auto_restore', 05:55:30 INFO - 'tabpaint']}, 05:55:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:55:30 INFO - 'ts_paint', 05:55:30 INFO - 'tpaint', 05:55:30 INFO - 'sessionrestore', 05:55:30 INFO - 'sessionrestore_no_auto_restore', 05:55:30 INFO - 'tabpaint']}, 05:55:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['a11yr', 05:55:30 INFO - 'ts_paint', 05:55:30 INFO - 'tpaint', 05:55:30 INFO - 'sessionrestore', 05:55:30 INFO - 'sessionrestore_no_auto_restore', 05:55:30 INFO - 'tabpaint']}, 05:55:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['a11yr', 05:55:30 INFO - 'ts_paint', 05:55:30 INFO - 'tpaint', 05:55:30 INFO - 'sessionrestore', 05:55:30 INFO - 'sessionrestore_no_auto_restore', 05:55:30 INFO - 'tabpaint']}, 05:55:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['tsvgx', 05:55:30 INFO - 'tsvgr_opacity', 05:55:30 INFO - 'tart', 05:55:30 INFO - 'tscrollx', 05:55:30 INFO - 'cart', 05:55:30 INFO - 'tsvg_static']}, 05:55:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:55:30 INFO - 'tsvgr_opacity', 05:55:30 INFO - 'tart', 05:55:30 INFO - 'tscrollx', 05:55:30 INFO - 'cart', 05:55:30 INFO - 'tsvg_static']}, 05:55:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'talos_options': ['--disable-e10s'], 05:55:30 INFO - 'tests': ['tp5o']}, 05:55:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:55:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'talos_options': ['--disable-e10s', 05:55:30 INFO - '--xperf_path', 05:55:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:55:30 INFO - 'tests': ['tp5n']}, 05:55:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:55:30 INFO - 'talos_options': ['--xperf_path', 05:55:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:55:30 INFO - 'tests': ['tp5n']}}, 05:55:30 INFO - 'talos.zip': {'path': '', 05:55:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:55:30 INFO - Running post-action listener: _resource_record_post_action 05:55:30 INFO - [mozharness: 2017-03-15 12:55:30.408354Z] Finished populate-webroot step (success) 05:55:30 INFO - [mozharness: 2017-03-15 12:55:30.408448Z] Running create-virtualenv step. 05:55:30 INFO - Running pre-action listener: _resource_record_pre_action 05:55:30 INFO - Running main action method: create_virtualenv 05:55:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:55:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:55:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:55:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:55:30 INFO - Using real prefix '/usr' 05:55:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:55:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:55:32 INFO - Installing pip.................done. 05:55:32 INFO - Return code: 0 05:55:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:55:33 INFO - Reading from file tmpfile_stdout 05:55:33 INFO - Output received: 05:55:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:55:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:33 INFO - Using env: {'DISPLAY': ':0', 05:55:33 INFO - 'HOME': '/home/cltbld', 05:55:33 INFO - 'LANG': 'en_US.UTF-8', 05:55:33 INFO - 'LANGUAGE': 'en_US:en', 05:55:33 INFO - 'LOGNAME': 'cltbld', 05:55:33 INFO - 'MAIL': '/var/mail/cltbld', 05:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:33 INFO - 'MOZ_NO_REMOTE': '1', 05:55:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:33 INFO - 'NO_EM_RESTART': '1', 05:55:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:33 INFO - 'PWD': '/builds/slave/test', 05:55:33 INFO - 'SHELL': '/bin/bash', 05:55:33 INFO - 'SHLVL': '1', 05:55:33 INFO - 'TERM': 'linux', 05:55:33 INFO - 'TMOUT': '86400', 05:55:33 INFO - 'USER': 'cltbld', 05:55:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:33 INFO - '_': '/tools/buildbot/bin/python'} 05:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:55:33 INFO - Cleaning up... 05:55:33 INFO - Return code: 0 05:55:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:55:33 INFO - Using env: {'DISPLAY': ':0', 05:55:33 INFO - 'HOME': '/home/cltbld', 05:55:33 INFO - 'LANG': 'en_US.UTF-8', 05:55:33 INFO - 'LANGUAGE': 'en_US:en', 05:55:33 INFO - 'LOGNAME': 'cltbld', 05:55:33 INFO - 'MAIL': '/var/mail/cltbld', 05:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:33 INFO - 'MOZ_NO_REMOTE': '1', 05:55:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:33 INFO - 'NO_EM_RESTART': '1', 05:55:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:33 INFO - 'PWD': '/builds/slave/test', 05:55:33 INFO - 'SHELL': '/bin/bash', 05:55:33 INFO - 'SHLVL': '1', 05:55:33 INFO - 'TERM': 'linux', 05:55:33 INFO - 'TMOUT': '86400', 05:55:33 INFO - 'USER': 'cltbld', 05:55:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:33 INFO - '_': '/tools/buildbot/bin/python'} 05:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:33 INFO - Downloading/unpacking psutil>=3.1.1 05:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:55:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:35 INFO - Installing collected packages: psutil 05:55:35 INFO - Running setup.py install for psutil 05:55:35 INFO - building 'psutil._psutil_linux' extension 05:55:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:55:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:55:35 INFO - building 'psutil._psutil_posix' extension 05:55:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:55:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:55:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:36 INFO - Successfully installed psutil 05:55:36 INFO - Cleaning up... 05:55:36 INFO - Return code: 0 05:55:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:55:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:36 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:55:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:55:36 INFO - Using env: {'DISPLAY': ':0', 05:55:36 INFO - 'HOME': '/home/cltbld', 05:55:36 INFO - 'LANG': 'en_US.UTF-8', 05:55:36 INFO - 'LANGUAGE': 'en_US:en', 05:55:36 INFO - 'LOGNAME': 'cltbld', 05:55:36 INFO - 'MAIL': '/var/mail/cltbld', 05:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:36 INFO - 'MOZ_NO_REMOTE': '1', 05:55:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:36 INFO - 'NO_EM_RESTART': '1', 05:55:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:36 INFO - 'PWD': '/builds/slave/test', 05:55:36 INFO - 'SHELL': '/bin/bash', 05:55:36 INFO - 'SHLVL': '1', 05:55:36 INFO - 'TERM': 'linux', 05:55:36 INFO - 'TMOUT': '86400', 05:55:36 INFO - 'USER': 'cltbld', 05:55:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:36 INFO - '_': '/tools/buildbot/bin/python'} 05:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:55:38 INFO - Installing collected packages: mozsystemmonitor 05:55:38 INFO - Running setup.py install for mozsystemmonitor 05:55:38 INFO - Successfully installed mozsystemmonitor 05:55:38 INFO - Cleaning up... 05:55:38 INFO - Return code: 0 05:55:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:55:38 INFO - Using env: {'DISPLAY': ':0', 05:55:38 INFO - 'HOME': '/home/cltbld', 05:55:38 INFO - 'LANG': 'en_US.UTF-8', 05:55:38 INFO - 'LANGUAGE': 'en_US:en', 05:55:38 INFO - 'LOGNAME': 'cltbld', 05:55:38 INFO - 'MAIL': '/var/mail/cltbld', 05:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:38 INFO - 'MOZ_NO_REMOTE': '1', 05:55:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:38 INFO - 'NO_EM_RESTART': '1', 05:55:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:38 INFO - 'PWD': '/builds/slave/test', 05:55:38 INFO - 'SHELL': '/bin/bash', 05:55:38 INFO - 'SHLVL': '1', 05:55:38 INFO - 'TERM': 'linux', 05:55:38 INFO - 'TMOUT': '86400', 05:55:38 INFO - 'USER': 'cltbld', 05:55:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:38 INFO - '_': '/tools/buildbot/bin/python'} 05:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:38 INFO - Downloading/unpacking jsonschema==2.5.1 05:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:55:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:41 INFO - Downloading functools32-3.2.3-2.tar.gz 05:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:55:41 INFO - warning: no files found matching '*.txt' 05:55:41 INFO - no previously-included directories found matching 'build' 05:55:41 INFO - no previously-included directories found matching 'dist' 05:55:41 INFO - no previously-included directories found matching '.git*' 05:55:41 INFO - Installing collected packages: jsonschema, functools32 05:55:41 INFO - Running setup.py install for functools32 05:55:41 INFO - warning: no files found matching '*.txt' 05:55:41 INFO - no previously-included directories found matching 'build' 05:55:41 INFO - no previously-included directories found matching 'dist' 05:55:41 INFO - no previously-included directories found matching '.git*' 05:55:41 INFO - Successfully installed jsonschema functools32 05:55:41 INFO - Cleaning up... 05:55:41 INFO - Return code: 0 05:55:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:41 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:55:41 INFO - Using env: {'DISPLAY': ':0', 05:55:41 INFO - 'HOME': '/home/cltbld', 05:55:41 INFO - 'LANG': 'en_US.UTF-8', 05:55:41 INFO - 'LANGUAGE': 'en_US:en', 05:55:41 INFO - 'LOGNAME': 'cltbld', 05:55:41 INFO - 'MAIL': '/var/mail/cltbld', 05:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:41 INFO - 'MOZ_NO_REMOTE': '1', 05:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:41 INFO - 'NO_EM_RESTART': '1', 05:55:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:41 INFO - 'PWD': '/builds/slave/test', 05:55:41 INFO - 'SHELL': '/bin/bash', 05:55:41 INFO - 'SHLVL': '1', 05:55:41 INFO - 'TERM': 'linux', 05:55:41 INFO - 'TMOUT': '86400', 05:55:41 INFO - 'USER': 'cltbld', 05:55:41 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:41 INFO - '_': '/tools/buildbot/bin/python'} 05:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:55:41 INFO - Cleaning up... 05:55:41 INFO - Return code: 0 05:55:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:41 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:55:41 INFO - Using env: {'DISPLAY': ':0', 05:55:41 INFO - 'HOME': '/home/cltbld', 05:55:41 INFO - 'LANG': 'en_US.UTF-8', 05:55:41 INFO - 'LANGUAGE': 'en_US:en', 05:55:41 INFO - 'LOGNAME': 'cltbld', 05:55:41 INFO - 'MAIL': '/var/mail/cltbld', 05:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:41 INFO - 'MOZ_NO_REMOTE': '1', 05:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:41 INFO - 'NO_EM_RESTART': '1', 05:55:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:41 INFO - 'PWD': '/builds/slave/test', 05:55:41 INFO - 'SHELL': '/bin/bash', 05:55:41 INFO - 'SHLVL': '1', 05:55:41 INFO - 'TERM': 'linux', 05:55:41 INFO - 'TMOUT': '86400', 05:55:41 INFO - 'USER': 'cltbld', 05:55:41 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:41 INFO - '_': '/tools/buildbot/bin/python'} 05:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:55:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:55:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:55:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:44 INFO - Downloading docopt-0.6.1.tar.gz 05:55:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:55:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:55:44 INFO - Running setup.py install for blobuploader 05:55:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:55:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:55:44 INFO - Running setup.py install for requests 05:55:45 INFO - Running setup.py install for docopt 05:55:45 INFO - Successfully installed blobuploader requests docopt 05:55:45 INFO - Cleaning up... 05:55:45 INFO - Return code: 0 05:55:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:45 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:45 INFO - Using env: {'DISPLAY': ':0', 05:55:45 INFO - 'HOME': '/home/cltbld', 05:55:45 INFO - 'LANG': 'en_US.UTF-8', 05:55:45 INFO - 'LANGUAGE': 'en_US:en', 05:55:45 INFO - 'LOGNAME': 'cltbld', 05:55:45 INFO - 'MAIL': '/var/mail/cltbld', 05:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:45 INFO - 'MOZ_NO_REMOTE': '1', 05:55:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:45 INFO - 'NO_EM_RESTART': '1', 05:55:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:45 INFO - 'PWD': '/builds/slave/test', 05:55:45 INFO - 'SHELL': '/bin/bash', 05:55:45 INFO - 'SHLVL': '1', 05:55:45 INFO - 'TERM': 'linux', 05:55:45 INFO - 'TMOUT': '86400', 05:55:45 INFO - 'USER': 'cltbld', 05:55:45 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:45 INFO - '_': '/tools/buildbot/bin/python'} 05:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:45 INFO - Running setup.py (path:/tmp/pip-isx8CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:45 INFO - Running setup.py (path:/tmp/pip-6LAJvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:45 INFO - Running setup.py (path:/tmp/pip-PXU4MF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:45 INFO - Running setup.py (path:/tmp/pip-wUihgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:45 INFO - Running setup.py (path:/tmp/pip-QYTaw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:45 INFO - Running setup.py (path:/tmp/pip-tyONxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:45 INFO - Running setup.py (path:/tmp/pip-2VD_ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:46 INFO - Running setup.py (path:/tmp/pip-PsUfrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:46 INFO - Running setup.py (path:/tmp/pip-eMGQfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:46 INFO - Running setup.py (path:/tmp/pip-fucdrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:46 INFO - Running setup.py (path:/tmp/pip-nUNTsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:46 INFO - Running setup.py (path:/tmp/pip-RNyRfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:46 INFO - Running setup.py (path:/tmp/pip-qUZFMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:46 INFO - Running setup.py (path:/tmp/pip-sIJzQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:46 INFO - Running setup.py (path:/tmp/pip-3bKytz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:46 INFO - Running setup.py (path:/tmp/pip-oYZVKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:46 INFO - Running setup.py (path:/tmp/pip-V8zVvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:55:46 INFO - Running setup.py install for manifestparser 05:55:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:55:46 INFO - Running setup.py install for mozcrash 05:55:46 INFO - Running setup.py install for mozdebug 05:55:47 INFO - Running setup.py install for mozdevice 05:55:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Running setup.py install for mozfile 05:55:47 INFO - Running setup.py install for mozhttpd 05:55:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Running setup.py install for mozinfo 05:55:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Running setup.py install for mozInstall 05:55:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Running setup.py install for mozleak 05:55:47 INFO - Running setup.py install for mozlog 05:55:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Running setup.py install for moznetwork 05:55:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:55:47 INFO - Running setup.py install for mozprocess 05:55:47 INFO - Running setup.py install for mozprofile 05:55:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:55:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:55:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:55:48 INFO - Running setup.py install for mozrunner 05:55:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:55:48 INFO - Running setup.py install for mozscreenshot 05:55:48 INFO - Running setup.py install for moztest 05:55:48 INFO - Running setup.py install for mozversion 05:55:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:55:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:55:48 INFO - Cleaning up... 05:55:48 INFO - Return code: 0 05:55:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:48 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:48 INFO - Using env: {'DISPLAY': ':0', 05:55:48 INFO - 'HOME': '/home/cltbld', 05:55:48 INFO - 'LANG': 'en_US.UTF-8', 05:55:48 INFO - 'LANGUAGE': 'en_US:en', 05:55:48 INFO - 'LOGNAME': 'cltbld', 05:55:48 INFO - 'MAIL': '/var/mail/cltbld', 05:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:48 INFO - 'MOZ_NO_REMOTE': '1', 05:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:48 INFO - 'NO_EM_RESTART': '1', 05:55:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:48 INFO - 'PWD': '/builds/slave/test', 05:55:48 INFO - 'SHELL': '/bin/bash', 05:55:48 INFO - 'SHLVL': '1', 05:55:48 INFO - 'TERM': 'linux', 05:55:48 INFO - 'TMOUT': '86400', 05:55:48 INFO - 'USER': 'cltbld', 05:55:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:48 INFO - '_': '/tools/buildbot/bin/python'} 05:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:48 INFO - Running setup.py (path:/tmp/pip-uM4K8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:48 INFO - Running setup.py (path:/tmp/pip-7leuQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:48 INFO - Running setup.py (path:/tmp/pip-1NuWOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:49 INFO - Running setup.py (path:/tmp/pip-KEEjKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:49 INFO - Running setup.py (path:/tmp/pip-rMZJiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:49 INFO - Running setup.py (path:/tmp/pip-pDBmG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:49 INFO - Running setup.py (path:/tmp/pip-yvGro_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:49 INFO - Running setup.py (path:/tmp/pip-n5EvAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:49 INFO - Running setup.py (path:/tmp/pip-DtjTec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:49 INFO - Running setup.py (path:/tmp/pip-8ranv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:49 INFO - Running setup.py (path:/tmp/pip-1mJ_Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:49 INFO - Running setup.py (path:/tmp/pip-DjhGIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:49 INFO - Running setup.py (path:/tmp/pip-Q90V8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:49 INFO - Running setup.py (path:/tmp/pip-0Q8aoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:49 INFO - Running setup.py (path:/tmp/pip-c9f2hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:49 INFO - Running setup.py (path:/tmp/pip-Zrpmnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:49 INFO - Running setup.py (path:/tmp/pip-cDrj3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:52 INFO - Downloading blessings-1.6.tar.gz 05:55:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:55:52 INFO - Installing collected packages: blessings 05:55:52 INFO - Running setup.py install for blessings 05:55:52 INFO - Successfully installed blessings 05:55:52 INFO - Cleaning up... 05:55:52 INFO - Return code: 0 05:55:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:52 INFO - Reading from file tmpfile_stdout 05:55:52 INFO - Current package versions: 05:55:52 INFO - argparse == 1.2.1 05:55:52 INFO - blessings == 1.6 05:55:52 INFO - blobuploader == 1.2.4 05:55:52 INFO - docopt == 0.6.1 05:55:52 INFO - functools32 == 3.2.3-2 05:55:52 INFO - jsonschema == 2.5.1 05:55:52 INFO - manifestparser == 1.1 05:55:52 INFO - mozInstall == 1.12 05:55:52 INFO - mozcrash == 1.0 05:55:52 INFO - mozdebug == 0.1 05:55:52 INFO - mozdevice == 0.49 05:55:52 INFO - mozfile == 1.2 05:55:52 INFO - mozhttpd == 0.7 05:55:52 INFO - mozinfo == 0.9 05:55:52 INFO - mozleak == 0.1 05:55:52 INFO - mozlog == 3.4 05:55:52 INFO - moznetwork == 0.27 05:55:52 INFO - mozprocess == 0.25 05:55:52 INFO - mozprofile == 0.28 05:55:52 INFO - mozrunner == 6.13 05:55:52 INFO - mozscreenshot == 0.1 05:55:52 INFO - mozsystemmonitor == 0.3 05:55:52 INFO - moztest == 0.8 05:55:52 INFO - mozversion == 1.4 05:55:52 INFO - psutil == 3.1.1 05:55:52 INFO - requests == 1.2.3 05:55:52 INFO - wsgiref == 0.1.2 05:55:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:52 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:52 INFO - Using env: {'DISPLAY': ':0', 05:55:52 INFO - 'HOME': '/home/cltbld', 05:55:52 INFO - 'LANG': 'en_US.UTF-8', 05:55:52 INFO - 'LANGUAGE': 'en_US:en', 05:55:52 INFO - 'LOGNAME': 'cltbld', 05:55:52 INFO - 'MAIL': '/var/mail/cltbld', 05:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:52 INFO - 'MOZ_NO_REMOTE': '1', 05:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:52 INFO - 'NO_EM_RESTART': '1', 05:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:52 INFO - 'PWD': '/builds/slave/test', 05:55:52 INFO - 'SHELL': '/bin/bash', 05:55:52 INFO - 'SHLVL': '1', 05:55:52 INFO - 'TERM': 'linux', 05:55:52 INFO - 'TMOUT': '86400', 05:55:52 INFO - 'USER': 'cltbld', 05:55:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:52 INFO - '_': '/tools/buildbot/bin/python'} 05:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:55:52 INFO - Cleaning up... 05:55:52 INFO - Return code: 0 05:55:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:52 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 0x1b261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, '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': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:55:52 INFO - Using env: {'DISPLAY': ':0', 05:55:52 INFO - 'HOME': '/home/cltbld', 05:55:52 INFO - 'LANG': 'en_US.UTF-8', 05:55:52 INFO - 'LANGUAGE': 'en_US:en', 05:55:52 INFO - 'LOGNAME': 'cltbld', 05:55:52 INFO - 'MAIL': '/var/mail/cltbld', 05:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:52 INFO - 'MOZ_NO_REMOTE': '1', 05:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:52 INFO - 'NO_EM_RESTART': '1', 05:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:52 INFO - 'PWD': '/builds/slave/test', 05:55:52 INFO - 'SHELL': '/bin/bash', 05:55:52 INFO - 'SHLVL': '1', 05:55:52 INFO - 'TERM': 'linux', 05:55:52 INFO - 'TMOUT': '86400', 05:55:52 INFO - 'USER': 'cltbld', 05:55:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:52 INFO - '_': '/tools/buildbot/bin/python'} 05:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:55:52 INFO - Cleaning up... 05:55:52 INFO - Return code: 0 05:55:52 INFO - Running post-action listener: _resource_record_post_action 05:55:52 INFO - Running post-action listener: _start_resource_monitoring 05:55:52 INFO - Starting resource monitoring. 05:55:52 INFO - [mozharness: 2017-03-15 12:55:52.981452Z] Finished create-virtualenv step (success) 05:55:52 INFO - [mozharness: 2017-03-15 12:55:52.981937Z] Running install step. 05:55:52 INFO - Running pre-action listener: _resource_record_pre_action 05:55:52 INFO - Running main action method: install 05:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:53 INFO - Reading from file tmpfile_stdout 05:55:53 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:55:53 INFO - Reading from file tmpfile_stdout 05:55:53 INFO - Output received: 05:55:53 INFO - Usage: mozinstall [options] installer 05:55:53 INFO - Options: 05:55:53 INFO - -h, --help show this help message and exit 05:55:53 INFO - -d DEST, --destination=DEST 05:55:53 INFO - Directory to install application into. [default: 05:55:53 INFO - "/builds/slave/test"] 05:55:53 INFO - --app=APP Application being installed. [default: firefox] 05:55:53 INFO - mkdir: /builds/slave/test/build/application 05:55:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:56:09 INFO - Reading from file tmpfile_stdout 05:56:09 INFO - Output received: 05:56:09 INFO - /builds/slave/test/build/application/firefox/firefox 05:56:09 INFO - Running post-action listener: _resource_record_post_action 05:56:09 INFO - [mozharness: 2017-03-15 12:56:09.997118Z] Finished install step (success) 05:56:09 INFO - [mozharness: 2017-03-15 12:56:09.997390Z] Running run-tests step. 05:56:09 INFO - Running pre-action listener: _resource_record_pre_action 05:56:09 INFO - Running main action method: run_tests 05:56:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:56:10 INFO - Python 2.7.3 05:56:10 INFO - Return code: 0 05:56:10 INFO - grabbing minidump binary from tooltool 05:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:10 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 0x1dc1400>, '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 0x1dc23b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:56:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:56:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:56:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:56:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:56:10 INFO - Return code: 0 05:56:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:56:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:56:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:56:10 INFO - ENV: RUST_BACKTRACE is now 1 05:56:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:56:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:56:10 INFO - Using env: {'DISPLAY': ':0', 05:56:10 INFO - 'HOME': '/home/cltbld', 05:56:10 INFO - 'LANG': 'en_US.UTF-8', 05:56:10 INFO - 'LANGUAGE': 'en_US:en', 05:56:10 INFO - 'LOGNAME': 'cltbld', 05:56:10 INFO - 'MAIL': '/var/mail/cltbld', 05:56:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:56:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:10 INFO - 'MOZ_NO_REMOTE': '1', 05:56:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:10 INFO - 'NO_EM_RESTART': '1', 05:56:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:10 INFO - 'PWD': '/builds/slave/test', 05:56:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:56:10 INFO - 'RUST_BACKTRACE': '1', 05:56:10 INFO - 'SHELL': '/bin/bash', 05:56:10 INFO - 'SHLVL': '1', 05:56:10 INFO - 'TERM': 'linux', 05:56:10 INFO - 'TMOUT': '86400', 05:56:10 INFO - 'USER': 'cltbld', 05:56:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093', 05:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:10 INFO - '_': '/tools/buildbot/bin/python'} 05:56:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:56:10 INFO - mozversion application_buildid: 20170315120105 05:56:10 INFO - mozversion application_changeset: 0cc9dced786cf2a3baeff707b636b4ad02637df0 05:56:10 INFO - mozversion application_display_name: Nightly 05:56:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:56:10 INFO - mozversion application_name: Firefox 05:56:10 INFO - mozversion application_remotingname: firefox 05:56:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:56:10 INFO - mozversion application_vendor: Mozilla 05:56:10 INFO - mozversion application_version: 55.0a1 05:56:10 INFO - mozversion platform_buildid: 20170315120105 05:56:10 INFO - mozversion platform_changeset: 0cc9dced786cf2a3baeff707b636b4ad02637df0 05:56:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:56:10 INFO - mozversion platform_version: 55.0a1 05:56:10 INFO - using testdate: 1489582570 05:56:10 INFO - actual date: 1489582570 05:56:10 INFO - starting webserver on 'localhost:42063' 05:56:10 INFO - SUITE-START | Running 2 tests 05:56:10 INFO - TEST-START | dromaeo_css 05:56:10 INFO - operating with platform_type : linux_ 05:56:10 INFO - Initialising browser for dromaeo_css test... 05:56:10 INFO - TEST-INFO | started process 14148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbUqCU2/profile http://localhost:42063/getInfo.html) 05:56:13 INFO - PID 14148 | __metrics Screen width/height:1600/1200 05:56:13 INFO - PID 14148 | colorDepth:24 05:56:13 INFO - PID 14148 | Browser inner width/height: 1024/697 05:56:13 INFO - PID 14148 | __metrics 05:56:14 INFO - PID 14148 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:56:14 INFO - PID 14148 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:56:15 INFO - TEST-INFO | 14148: exit 0 05:56:15 INFO - Browser initialized. 05:56:15 INFO - Running cycle 1/1 for dromaeo_css test... 05:56:15 INFO - TEST-INFO | started process 14238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbUqCU2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:27 INFO - PID 14238 | RSS: Main: 144338944 05:56:27 INFO - PID 14238 | 05:59:29 INFO - PID 14238 | Cycle 1(1): loaded http://localhost:42063/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42063/tests/dromaeo/cssquery-ext.html) 05:59:31 INFO - PID 14238 | RSS: Main: 271572992 05:59:31 INFO - PID 14238 | 06:01:17 INFO - PID 14238 | ************************* 06:01:17 INFO - PID 14238 | A coding exception was thrown and uncaught in a Task. 06:01:17 INFO - PID 14238 | 06:01:17 ERROR - PID 14238 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:01:17 INFO - PID 14238 | Full stack: 06:01:17 INFO - PID 14238 | ************************* 06:02:33 INFO - PID 14238 | Cycle 1(1): loaded http://localhost:42063/tests/dromaeo/cssquery-ext.html (next: http://localhost:42063/tests/dromaeo/cssquery-jquery.html) 06:02:33 INFO - PID 14238 | RSS: Main: 234889216 06:02:33 INFO - PID 14238 | 06:05:35 INFO - PID 14238 | Cycle 1(1): loaded http://localhost:42063/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42063/tests/dromaeo/cssquery-mootools.html) 06:05:36 INFO - PID 14238 | RSS: Main: 179945472 06:05:36 INFO - PID 14238 | 06:08:38 INFO - PID 14238 | Cycle 1(1): loaded http://localhost:42063/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42063/tests/dromaeo/cssquery-prototype.html) 06:08:39 INFO - PID 14238 | RSS: Main: 177348608 06:08:39 INFO - PID 14238 | 06:11:41 INFO - PID 14238 | Cycle 1(1): loaded http://localhost:42063/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42063/tests/dromaeo/cssquery-yui.html) 06:11:42 INFO - PID 14238 | RSS: Main: 184418304 06:11:42 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | Cycle 1(1): loaded http://localhost:42063/tests/dromaeo/cssquery-yui.html (next: http://localhost:42063/tests/dromaeo/cssquery-dojo.html) 06:14:45 INFO - PID 14238 | RSS: Main: 276692992 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | __start_tp_report 06:14:45 INFO - PID 14238 | _x_x_mozilla_page_load 06:14:45 INFO - PID 14238 | _x_x_mozilla_page_load_details 06:14:45 INFO - PID 14238 | |i|pagename|runs| 06:14:45 INFO - PID 14238 | |0;dojo.html;3198.9680103198966;3223.967760322396;3256.023193042088;3260.136063943057;3279.9672003279975;5287.841364759054;5392.757325920334;5446.53704435123;5482.862928426786;5502.202180683798;5485.4788795064505;5506.256655351526;5527.364353099395;5535.308086489189;5570.805021824237;4803.039392121572;4833.226683730604;4879.463259041511;4950.455449900508;4983.401991760976;4920.557149856512;4936.827211047614;4987.700737955734;4988.625853061017;5026;4798.880027999293;4805.591524720403;4849.3453383793085;4852.757362131879;4909.680870743408;4802.663813533054;4867.415910090776;4868.099401610713;4869.293952376921;4882.145624515695;4754.690945088558;4875.073735990186;4891.584215341701;4923.458419573844;4957.82647607332;5422.1053526168525;5432.891342173174;5450.509454149106;5473.2063850741815;5508;5712.65724056558;5804.100364443518;5898.14476900847;5912.201852749867;5921.970390148064;5821.50517206038;5829.979753543113;5902.085176797603;5907.379725128842;5922.674252916088;5521.309836270466;5531.059719847555;5531.446855314517;5566.276384070045;5579.6931168785295;6009.06859436788;6061.4544690977145;6068.362821903725;6068.726907289182;6074.574779765462;5556.666600004012;5629.577781666391;5631.465010823965;5801.332846722681;5807.216025836543;5782.566307526952;5802.6808525531505;5839.007368747323;5839.795607153729;5844.766209351664;5872.618279811799;5874.941250587525;5875.500582450454;5880.676562789087;5893.499052580493;5689.431056894277;5771.769129234867;5872.530197584183;5885.793997210077;5888.735006924699;4522.276435770261;4535.478419981679;4545.2954792007295;4550.704204226715;4595.2188128018315;4062.6343629072935;4069.348904175377;4076.9388459173138;4081.4898137732785;4122.3404255319;5571.637843540238;5576.470235327637;5577.776888924479;5579.637323574036;5599.272094627672;5755.625884317506;5821.18503409523;5850.824475265749;5901.940980590139;5908.793192238251;5562.137868630369;5568;5570.944290557042;5573.108302671553;5600.6919619420505;5014.749262536859;5060.645754797202;5067.454426399983;5088.77100530477;5114.977004599095;5888.175655408247;6056.636601803906;6110.3461303294;6159.076138579249;6177.4749146322165;5913.91129133072;5916.053431450947;5919.141724450017;5946.28644562638;5971.940280597139;6153.169322141633;6154.599951003171;6155.692215389302;6156.969215153896;6159.846003849761;5587.664740115606;5652.491275785199;5795.478406943395;5829.621074630136;5833.79581714638;5902.203202567598;5956.285245770535;5964.343922168471;5996.490298324691;6002.249718785152;5734;5782.3639399666845;5783.958887400308;5788.363280039276;5796.739146738322;5603.159526070959;5641.548676105819;5646.576506762091;5652.858678532905;5778.219940308005;5732.971335143297;5772.307323121252;5803.564732644984;5818.981678206213;5838.153467747238;5711.143328500758;5728.255326807655;5732.34078081026;5733.082706766897;5738.942610573841;5476.972615136899;5578.832635020955;5579.163125531203;5583.246261754611;5586.190002449704 06:14:45 INFO - PID 14238 | |1;ext.html;701.824543864034;753.5629334985708;777.1490218211059;824.5671022713071;839.4333824668347;28937.855310723444;36294.45558316627;36795.44806827893;36903.261934761285;36961.26077478449;38673.22653546927;39134.21731565374;39137.804310978405;39146.8042659787;39187;7022.438204943618;7187.784366468988;8623.396362254645;8823.749943764331;8831.072737362581;9822;10040.89959100407;10051.798964020716;10052.145567626723;10059.899401006007;10322.122619577307;10444.791104177912;10445.164386849034;10452.738681532985;10457.686269411892;10259.538320775582;10373.533191006423;10377.377357358508;10377.688669339932;10382.532786024647;9975;10047.799044019152;10051.447170405698;10056;10065.748356291078;40506.594934050576;40856.38715419271;40880;40885;40979;3888.2612303662213;3940.6650434713083;3943;3970.047188674726;3983.780892050936;27746.722532774616;27928.30179245535;28261;28310.292242693893;29481;4156.667466602665;4169.958300417018;4176.227397931392;4176.728512646699;4264.360345948102;208265;209062;209701;210551;211442;49078;49151.75424122928;49225.50774492209;49284.50715492871;49408.25887611689;50807.49192508101;50835;50920.23619645708;50988.74505627448;51040.234396484084;13620.659483512793;13884.652883677989;13886.652833679036;13892;13911.373988170557;8840.778980525538;9021.413608115452;10410.791784164274;11158.776824463464;11159.609413670645;12456.190347627375;12510.624681259576;12580.685482862818;12611.369431528386;12783.977281817619;5091.8981620367385;5329.333833270841;5344.385395679469;5376.327959005124;5383.246345511631;918.5453200665713;938.9624415023459;946.2240962410895;950.3300173377752;961.110972350573;24133.276001719976;26015.66678830835;26273.868630657107;26368;26569.46861062768;1004.4425343934187;1010.7119470950744;1017.8982101789762;1022.4683164754287;1025.887152413234;13003.804942925864;13760.793588096187;13866.237356945465;14068.648283792782;14150;214396;217249;217617;217902;292620;49464.752676237105;49596.25605615919;49752.25371619429;49753.25370119451;49797.253041204414;1333.2134040915905;1364.7884577890443;1384.8199734034713;1385.515069725588;1388.3266615691382;25173.74826251714;25545.744542554337;27733.890644373998;28024.159275221777;28985.855070725356;41563.7921810389;41609.37585936274;41613;41664.58335416607;41715.7914210427;234784;235420;235722;235728;236068;29760.10719678413;30253.394932101673;30428.087157385315;30963.380732385678;30986.225344366572;30430;32889.50665739968;37928;38440.6155938437;39043;31851.20371990719;32367.676323236465;32379.35241295114;32624.510632341007;33334.33331333311;31539.52690709595;32178.678213218504;32328.515072273465;32952;33460.33079338351;33879.830600846835;34112.48831267583;34129;34190.31619367649;36094.63905360913;31029.8448507756;31338.529922050733;32098;32220.35559288848;32226.516602250515;25154.37114072163;25221.74778252194;25449.491010180063;25557.872210639573;25597.23208303754 06:14:45 INFO - PID 14238 | |2;jquery.html;427.337626678648;429.86459265331416;440.3416891746837;442.84057739213887;461.156084365611;18703.5324116897;19912.40262792117;20511.948805119482;20651.277205297818;20707.275245366414;19753.407397778054;21608.56782864342;21840.126394944222;21893.67159492609;22109.226177083805;6474.3525647435235;6834.1457317835275;6834.2482326944;6835.863282734343;6852.897206541906;6380.298167201604;6735.831604209885;6770.763023294186;6774.119364482611;6779.389854913057;6531.150950376445;6751.763688270911;6770.086038384804;6776.356246156632;6779.661016949158;6772.864542709143;6826.24911259761;6834;6835.07726456927;6838.384545390939;6445.613263204223;6461.095446637474;6550.606963582152;6563.54055216135;6564.573302735307;24510.264692059267;24612.50774984508;24744.25767226986;24770.50458990828;24830.379240518952;2864.2266588021325;2945.190072729995;3009.8796048158047;3019.1999120233154;3060.32672811981;15186.392544298325;15860.60348491297;16094.275757590936;16199.919000404923;16820.66358672832;2053.5009443106464;2074.5643414882743;2084.666453367454;2100.0549752611387;2135.572885422906;107685;107841.46079269632;108700;109244;109443;17936.282548698167;18268;18361.54096147607;18481.815181848277;18501;32741.69033238644;32758.18104547358;32834.835825821196;32893.3421331572;33105.337893242;9107.180353768194;9110.86333704995;9192.63229470827;9402.200812930978;9441;7276.163241227327;7319.085114360705;7507.587082710504;7516.962415187889;7537;9553.856692149624;9650;9792.216622670312;9899;10112.898871011195;3438.621751607321;3632.055665526982;3798.924021519554;3995.7003224757677;4007.979960100181;1118.6743708705048;1140.948657310423;1142.3378983578873;1165.1901928159848;1181.1199317707487;22158.781267030146;22323.011833344142;22607.660885086738;22614.26864567361;22630.66054009191;1125.5104029747085;1139.2879450343535;1142.497301187475;1157.7337212441187;1170.0814860334597;12206.267623942593;12218.511259549696;12571.08590275345;12629.2422454653;13444.596662100153;677798;679044;684169;687705;694418;82468.58765706133;83310;92665.07334926564;94672.52663736638;109486;1318.0773458579033;1365.0717512091874;1391.325207274471;1394.3655636685285;1410.3441899516774;20146.294879679142;20317.79682203159;20817;20992.895035524725;21027.369178924655;50949.7452512735;51152.23271650854;51178;51952;52612.73693631507;68728;68784;69218.30781692118;69456.305436947;70821;11735.765284694087;11810.586629467927;12101;12197.695057623276;12317.568885088978;14301.28493575338;14595.92702036483;14628.634284142981;14634.926825365805;14761.188134652697;12052.758944820878;12069.396530173632;12106.394680266128;12206.633800985985;12505.874001419963;12269.631911042683;12738.745225095261;12744.553940612033;12799.744005120032;13149.408276627766;12516.31160286193;12518.749625007631;12589.118761686594;12600.810987835373;12606.621801345975;12043.277403355827;12213.877861221274;12233.632991010283;12383.257004579755;12527.310997895203;12869.420876060787;12910.67723306925;12912.289824059766;12959.41682624303;12980.805287920877 06:14:45 INFO - PID 14238 | |3;mootools.html;1358.97961530577;1479.7558402863528;1502.3164460170624;1503.5940296120048;1582.857542821146;8824.161704638058;10502.527386267617;10794.568217271319;10851.565937362506;10929.617463388782;10559.208059395538;10644.99999999998;10649.148068154549;10658.307210031362;10970.780584388307;1825.9908700456479;1880.699088145897;2209.524952135295;2243.0467051503047;2245.6856040154394;2167;2246.1801442473543;2248.4603695113137;2253.425376528991;2253.6394176931694;2213.5351576169023;2225.198928385788;2237.2393386248673;2241.1707668162803;2242.955140897181;2182.7598964113936;2191.485001024758;2191.835612329082;2193.616117179487;2197.1321328075483;2062.453449835795;2181.029441898356;2181.476445653048;2186.497105665706;2188.081005977477;10840.67477975662;10904.563817447293;10969.23215374924;10970.286931349438;10974.890251097546;5342.519173274385;5528.778848846041;5534.418886016951;5534.446555344473;5538.113901775737;3145.9685403145904;3253.4631785755323;3287.211069343364;3290.424175769255;3326.6340702522702;1184.7926612842718;1235.6849003504049;1237.3874931908672;1271.319843883527;1274.0635632809876;15391.615209619737;15490.9225453872;15500;15523.146226957513;15523.534293971199;4284.400183974246;4435.334699795057;4509.954900451019;4528.45658520973;4553.499115097403;13223.801642975362;13253.337333133304;13273;13281.933590331986;13288.601341959757;5534.557235421156;5553.805616803393;5570.025245582007;5573.944260557342;5578.274824272899;3994;4477.888052798641;4883.072216278895;4965.90068198634;5043.420006699276;3604.2070744436182;3721.8697345592773;3723.404255319136;3798.1454172810895;3820.8471661133244;1030.9896901031043;1035.089121573026;1043.4209013997154;1048.4705223861902;1058.2433560004633;956.2302346610905;961.6009356117133;967.7870868408938;988.6094992477931;995.2187532786728;6412.665413984701;6620.304867988888;6636.037774522667;6637.867242655217;6808.2170550386345;957.5116690487902;974.4835237324229;987.2200961240683;1017.2828156149897;1025.7230547752065;4101.282275601818;4240.575942405735;4280.186764514732;4330.870073897788;4404.537523560044;13167.868321316855;13583.049186557044;13584.049116561746;13614;13615.31923403826;13224.867751322556;13351.799723004162;13425.932870335586;13476.730465390638;13608.183508989492;9802.803943921082;9829.950850245703;9875.654352097643;9928.702138935989;9985.800283994426;6434.742610295723;6439.452646525097;6481.837954051187;6539.869202615826;6554.594320939798;11697.473613687234;11706.35615041181;11900.583479578174;11934.164608477324;12019.699507512381;12037.277763334228;12310.26138431697;12311;12321.815172772236;12407.317597532223;7780.92219077802;7868.449208555347;9094.545272736468;9211.078892110736;9239.953800230956;10081.294309398272;10136.695899123039;10215.897841021495;10237.74405639865;10265.948670256601;8914.643414263357;8999.955000224956;9017.684381046633;9118.270538356783;9213;8657.783555411164;8679;8856.512891791013;8867.467951922905;9090.90909090927;7673.884891726517;7695.57674327895;7768.067831860213;7777.222277772177;7919.445638805229;5502.119660854244;5599.888002239851;5712.571557133148;5742.2150257126095;5797.623154494944;5752.583914755729;5827.825165245049;5840.445157710011;6023.728932198149;6033.879322413615 06:14:45 INFO - PID 14238 | |4;prototype.html;412.10984273968216;413.8737685006073;419.3039554339796;421.9810108545115;431.44774688398843;5674.773009079637;5719.227904232927;5723.627964182325;5726.68503232322;5781.739821708023;5278.894422111555;5554.611177217596;5588.463172627519;5615.494605485505;5648.887022259552;4436.268015777393;4583.541645835423;4629.911970686886;4632.976835115836;4650.697704649196;4585.679002469828;4604.792784324697;4608.216603177468;4626.097910907374;4629.259318509039;4594.1271158479785;4607.35497030416;4607.976960115195;4627.8611641650805;4633.49031606523;4497.235469970113;4591.334256532784;4628;4628.583427491509;4642.767861606923;4309.396684464177;4314.741115533078;4318.352247162919;4330.891727706832;4331.7617531035785;5488.780448782044;5515.586331025149;5530.11518157097;5534.557235421197;5552.7778888844805;5282.577393808486;5485.835424937259;5499.175123731432;5504.376209018349;5538.363088244863;5929.1995580596495;5952.494038006775;5966.090850927705;5978.790742323998;5996.100584912255;4782.808687652489;4890.975545122287;4892.559669629715;4898.265260210961;4912.435069966964;5917.289925208959;6096.116063170815;6264.624122552661;6291.43377096059;6315.557910946236;4871.171900776876;4974.850754477371;4993.700377977333;5012.248162775612;5030.094582975026;5105.770240339194;5107.565856902131;5124.436312005676;5124.897502049938;5126.307948426915;5375.731213439312;5427.511523962862;5444.537214336747;5483.643563168461;5486.478784515464;5907.113932910098;5910.438508341701;5922.911156332659;5943.613665111754;5946.256717910262;1748.7988881278816;1764.7176451767655;1777.0315178227897;1781.0026385224057;1788.2936240185313;1333.7132516508802;1343.8857697095661;1355.1801160298076;1356.3964036004081;1375.6354566040006;5558.471945165203;5702.714864256771;5703.201551782754;5714.828555143353;5741.626794258444;6076.848078798065;6136.453371850721;6136.539759518054;6143.139960405547;6176.505879529627;5479.917801232985;5694.686792226385;5722.7424765885635;5727.140928860621;5774.220480235114;3229.870805167814;3248.447763880146;3251.5772949516413;3266.918327041843;3271.165852707544;6077.392260773976;6350.364963503613;6397.296297407282;6412.7495138448485;6433.967830160913;6308.148399965946;6340.302566717657;6349.809505714836;6349.9682501587195;6366.872662546723;6455.419012288822;6462.386073323027;6480.4815614750705;6483.957553370673;6497.5126865484335;5986.132010858489;6151.2618485781995;6175.166352542529;6201.441870231701;6226.40849119333;5754.4533269339345;6095.35998720128;6095.359987201457;6105.786297479567;6107.297660769068;6190.5547308811865;6266.561340706106;6447.129637498878;6456.644884531396;6476.5466417350335;4997.900041999213;5063.493650634907;5065.746712664279;5066.164082926276;5074.3657042869645;6026.517878569616;6167.383261673796;6176.382361763967;6213.937860621336;6238.750449982132;5974.2830860297045;5974.581779275409;5980.581359304807;5982.162497250475;5983.551233657406;5793.333766616893;5975.312839023568;5981.671008094597;6012.3386427493815;6014.789482368096;5949.583529153091;5983.978091064159;6002.669853158118;6020.367861374492;6024.156618073385;6000.939990600213;6015.248093988252;6023.759049637965;6061.333253342041;6066;5675.987430889867;5830.2129212555765;5854.824355269348;5859;5874.383189765013 06:14:45 INFO - PID 14238 | |5;yui.html;795.6936579416927;802.9842249554315;847.8770578266151;858.823941092076;864.4742030316488;16158.192090395478;16351.672966540662;16383.91808040961;16387.4264400746;16417.589560260985;14730;14830.55508334749;14865.777013344808;14921.104733715985;14921.403143874231;823.7199352220244;833.1585099049972;849.9575021248913;856.5717141429285;858.6093327535988;857.3526987124743;858.5063588436643;860.535310932099;860.6514361683528;861.1044513705739;899.5996781432265;899.685110211424;899.7570655922897;899.883015208022;900.3967341880956;892.2638822971074;894.2130924786212;895.4671970177734;895.5701263393574;895.7223260789111;653.3335997282793;654.8330175805187;655.5673255651295;656.3141517114626;656.8423578341211;16227.188640568042;16321.918390408091;16412.83587164137;16842.831571684248;16863.494095177164;15969.760453593206;16257.674846503121;16522;16648.80171902336;16737.91631041837;12169.191367706359;12478.313692746895;12615.621531354074;12967.351632418342;13385.19688818674;3837.81375315608;3837.9424308635394;3850.1144736710435;3930.960690393088;4029.476168098158;68240.6587967062;85730.57134714286;87981.56009219977;89328.55335723345;90475.54762226147;12612.684682882818;12724.491020359266;12780.809810804452;12827.679308017374;12946.287954162612;25508.362290942874;26094.34764130874;26137.346566335615;26337.73662263391;26404.73595264061;8737.475751454933;8772.38593298463;8779.648814047494;8869.68956086534;8957.462552246887;4478.686491945533;4483.170613436525;4546.477155127137;4575.130725162208;4584.4498660161;3409.5397121388296;3530.876419325363;3532.187596852687;3537.681608655182;3538.840752166159;571.9513841323534;576.4264556766045;587.1105275507609;594.7353427724621;596.1296507099684;735.63586024918;769.7382889817488;805.2631841864745;813.6257321631997;863.7797361672733;10191.439470829027;10661.360318380923;10809.945950270197;10945.890541094646;11320.943395282971;635.5678138865524;654.9476041916636;685.1298850459888;693.517742247524;815.3866856724303;4930.929009815886;5095.770690318945;5148.3564554430695;5150.819721309736;5174.508421699932;55710.44289557052;57433.425665742805;57903;63546;80037.59981200057;61301;61875.38124618786;62215;62667.686661566404;66316.33683663102;57353;58342;58387.70806145942;58467.70766146142;58587.12119318214;10571.278695076438;11021.118310534977;11158.55365785392;11205.383703896365;11282.661520154408;39815.20369592534;40020.399694005195;40859.79570102131;41170;41816;46833;46899.29651055305;47560.2865957004;47583.28625070696;49414.25878611752;13449.260290684106;13529.59411217665;13591;13783.37974791157;14009.159450433006;15487.93368007819;15537.76693349578;15603.765943510629;15980.041197528186;16034.599135021717;13398.598042058753;14605.92697036508;14861.777073344125;15682.607934801721;15689.843101568838;13885.166889986633;14454.132752034911;14592.854071459149;14878.925605372337;15109.697806044038;14197.077189982618;14769.778453322993;14892.478763243234;14916.328765205808;15290.77063844021;11300.547978080785;11565.059688701902;11957.76084478323;12139.150259482105;12339.93830030844;10981.176411768989;11906.345151016778;12122.393880306126;12155.696107597381;12213.816792748294 06:14:45 INFO - PID 14238 | __end_tp_report 06:14:45 INFO - PID 14238 | __start_cc_report 06:14:45 INFO - PID 14238 | _x_x_mozilla_cycle_collect,1344 06:14:45 INFO - PID 14238 | __end_cc_report 06:14:45 INFO - PID 14238 | __startTimestamp1489583685514__endTimestamp 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | ------- Summary: start ------- 06:14:45 INFO - PID 14238 | Number of tests: 6 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | [#0] dojo.html Cycles:165 Average:5446.39 Median:5603.16 stddev:620.53 (11.1%) stddev-sans-first:596.86 06:14:45 INFO - PID 14238 | Values: 3199.0 3224.0 3256.0 3260.1 3280.0 5287.8 5392.8 5446.5 5482.9 5502.2 5485.5 5506.3 5527.4 5535.3 5570.8 4803.0 4833.2 4879.5 4950.5 4983.4 4920.6 4936.8 4987.7 4988.6 5026.0 4798.9 4805.6 4849.3 4852.8 4909.7 4802.7 4867.4 4868.1 4869.3 4882.1 4754.7 4875.1 4891.6 4923.5 4957.8 5422.1 5432.9 5450.5 5473.2 5508.0 5712.7 5804.1 5898.1 5912.2 5922.0 5821.5 5830.0 5902.1 5907.4 5922.7 5521.3 5531.1 5531.4 5566.3 5579.7 6009.1 6061.5 6068.4 6068.7 6074.6 5556.7 5629.6 5631.5 5801.3 5807.2 5782.6 5802.7 5839.0 5839.8 5844.8 5872.6 5874.9 5875.5 5880.7 5893.5 5689.4 5771.8 5872.5 5885.8 5888.7 4522.3 4535.5 4545.3 4550.7 4595.2 4062.6 4069.3 4076.9 4081.5 4122.3 5571.6 5576.5 5577.8 5579.6 5599.3 5755.6 5821.2 5850.8 5901.9 5908.8 5562.1 5568.0 5570.9 5573.1 5600.7 5014.7 5060.6 5067.5 5088.8 5115.0 5888.2 6056.6 6110.3 6159.1 6177.5 5913.9 5916.1 5919.1 5946.3 5971.9 6153.2 6154.6 6155.7 6157.0 6159.8 5587.7 5652.5 5795.5 5829.6 5833.8 5902.2 5956.3 5964.3 5996.5 6002.2 5734.0 5782.4 5784.0 5788.4 5796.7 5603.2 5641.5 5646.6 5652.9 5778.2 5733.0 5772.3 5803.6 5819.0 5838.2 5711.1 5728.3 5732.3 5733.1 5738.9 5477.0 5578.8 5579.2 5583.2 5586.2 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | [#1] ext.html Cycles:180 Average:39022.18 Median:31695.37 stddev:58768.31 (185.4%) stddev-sans-first:58862.73 06:14:45 INFO - PID 14238 | Values: 701.8 753.6 777.1 824.6 839.4 28937.9 36294.5 36795.4 36903.3 36961.3 38673.2 39134.2 39137.8 39146.8 39187.0 7022.4 7187.8 8623.4 8823.7 8831.1 9822.0 10040.9 10051.8 10052.1 10059.9 10322.1 10444.8 10445.2 10452.7 10457.7 10259.5 10373.5 10377.4 10377.7 10382.5 9975.0 10047.8 10051.4 10056.0 10065.7 40506.6 40856.4 40880.0 40885.0 40979.0 3888.3 3940.7 3943.0 3970.0 3983.8 27746.7 27928.3 28261.0 28310.3 29481.0 4156.7 4170.0 4176.2 4176.7 4264.4 208265.0 209062.0 209701.0 210551.0 211442.0 49078.0 49151.8 49225.5 49284.5 49408.3 50807.5 50835.0 50920.2 50988.7 51040.2 13620.7 13884.7 13886.7 13892.0 13911.4 8840.8 9021.4 10410.8 11158.8 11159.6 12456.2 12510.6 12580.7 12611.4 12784.0 5091.9 5329.3 5344.4 5376.3 5383.2 918.5 939.0 946.2 950.3 961.1 24133.3 26015.7 26273.9 26368.0 26569.5 1004.4 1010.7 1017.9 1022.5 1025.9 13003.8 13760.8 13866.2 14068.6 14150.0 214396.0 217249.0 217617.0 217902.0 292620.0 49464.8 49596.3 49752.3 49753.3 49797.3 1333.2 1364.8 1384.8 1385.5 1388.3 25173.7 25545.7 27733.9 28024.2 28985.9 41563.8 41609.4 41613.0 41664.6 41715.8 234784.0 235420.0 235722.0 235728.0 236068.0 29760.1 30253.4 30428.1 30963.4 30986.2 30430.0 32889.5 37928.0 38440.6 39043.0 31851.2 32367.7 32379.4 32624.5 33334.3 31539.5 32178.7 32328.5 32952.0 33460.3 33879.8 34112.5 34129.0 34190.3 36094.6 31029.8 31338.5 32098.0 32220.4 32226.5 25154.4 25221.7 25449.5 25557.9 25597.2 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | [#2] jquery.html Cycles:180 Average:37726.56 Median:22001.45 stddev:112278.62 (510.3%) stddev-sans-first:112558.66 06:14:45 INFO - PID 14238 | Values: 427.3 429.9 440.3 442.8 461.2 18703.5 19912.4 20511.9 20651.3 20707.3 19753.4 21608.6 21840.1 21893.7 22109.2 6474.4 6834.1 6834.2 6835.9 6852.9 6380.3 6735.8 6770.8 6774.1 6779.4 6531.2 6751.8 6770.1 6776.4 6779.7 6772.9 6826.2 6834.0 6835.1 6838.4 6445.6 6461.1 6550.6 6563.5 6564.6 24510.3 24612.5 24744.3 24770.5 24830.4 2864.2 2945.2 3009.9 3019.2 3060.3 15186.4 15860.6 16094.3 16199.9 16820.7 2053.5 2074.6 2084.7 2100.1 2135.6 107685.0 107841.5 108700.0 109244.0 109443.0 17936.3 18268.0 18361.5 18481.8 18501.0 32741.7 32758.2 32834.8 32893.3 33105.3 9107.2 9110.9 9192.6 9402.2 9441.0 7276.2 7319.1 7507.6 7517.0 7537.0 9553.9 9650.0 9792.2 9899.0 10112.9 3438.6 3632.1 3798.9 3995.7 4008.0 1118.7 1140.9 1142.3 1165.2 1181.1 22158.8 22323.0 22607.7 22614.3 22630.7 1125.5 1139.3 1142.5 1157.7 1170.1 12206.3 12218.5 12571.1 12629.2 13444.6 677798.0 679044.0 684169.0 687705.0 694418.0 82468.6 83310.0 92665.1 94672.5 109486.0 1318.1 1365.1 1391.3 1394.4 1410.3 20146.3 20317.8 20817.0 20992.9 21027.4 50949.7 51152.2 51178.0 51952.0 52612.7 68728.0 68784.0 69218.3 69456.3 70821.0 11735.8 11810.6 12101.0 12197.7 12317.6 14301.3 14595.9 14628.6 14634.9 14761.2 12052.8 12069.4 12106.4 12206.6 12505.9 12269.6 12738.7 12744.6 12799.7 13149.4 12516.3 12518.7 12589.1 12600.8 12606.6 12043.3 12213.9 12233.6 12383.3 12527.3 12869.4 12910.7 12912.3 12959.4 12980.8 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | [#3] mootools.html Cycles:180 Average:6637.55 Median:2699.80 stddev:4268.31 (158.1%) stddev-sans-first:4261.86 06:14:45 INFO - PID 14238 | Values: 1359.0 1479.8 1502.3 1503.6 1582.9 8824.2 10502.5 10794.6 10851.6 10929.6 10559.2 10645.0 10649.1 10658.3 10970.8 1826.0 1880.7 2209.5 2243.0 2245.7 2167.0 2246.2 2248.5 2253.4 2253.6 2213.5 2225.2 2237.2 2241.2 2243.0 2182.8 2191.5 2191.8 2193.6 2197.1 2062.5 2181.0 2181.5 2186.5 2188.1 10840.7 10904.6 10969.2 10970.3 10974.9 5342.5 5528.8 5534.4 5534.4 5538.1 3146.0 3253.5 3287.2 3290.4 3326.6 1184.8 1235.7 1237.4 1271.3 1274.1 15391.6 15490.9 15500.0 15523.1 15523.5 4284.4 4435.3 4510.0 4528.5 4553.5 13223.8 13253.3 13273.0 13281.9 13288.6 5534.6 5553.8 5570.0 5573.9 5578.3 3994.0 4477.9 4883.1 4965.9 5043.4 3604.2 3721.9 3723.4 3798.1 3820.8 1031.0 1035.1 1043.4 1048.5 1058.2 956.2 961.6 967.8 988.6 995.2 6412.7 6620.3 6636.0 6637.9 6808.2 957.5 974.5 987.2 1017.3 1025.7 4101.3 4240.6 4280.2 4330.9 4404.5 13167.9 13583.0 13584.0 13614.0 13615.3 13224.9 13351.8 13425.9 13476.7 13608.2 9802.8 9830.0 9875.7 9928.7 9985.8 6434.7 6439.5 6481.8 6539.9 6554.6 11697.5 11706.4 11900.6 11934.2 12019.7 12037.3 12310.3 12311.0 12321.8 12407.3 7780.9 7868.4 9094.5 9211.1 9240.0 10081.3 10136.7 10215.9 10237.7 10265.9 8914.6 9000.0 9017.7 9118.3 9213.0 8657.8 8679.0 8856.5 8867.5 9090.9 7673.9 7695.6 7768.1 7777.2 7919.4 5502.1 5599.9 5712.6 5742.2 5797.6 5752.6 5827.8 5840.4 6023.7 6033.9 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | [#4] prototype.html Cycles:180 Average:5179.38 Median:5702.96 stddev:1405.72 (24.6%) stddev-sans-first:1363.36 06:14:45 INFO - PID 14238 | Values: 412.1 413.9 419.3 422.0 431.4 5674.8 5719.2 5723.6 5726.7 5781.7 5278.9 5554.6 5588.5 5615.5 5648.9 4436.3 4583.5 4629.9 4633.0 4650.7 4585.7 4604.8 4608.2 4626.1 4629.3 4594.1 4607.4 4608.0 4627.9 4633.5 4497.2 4591.3 4628.0 4628.6 4642.8 4309.4 4314.7 4318.4 4330.9 4331.8 5488.8 5515.6 5530.1 5534.6 5552.8 5282.6 5485.8 5499.2 5504.4 5538.4 5929.2 5952.5 5966.1 5978.8 5996.1 4782.8 4891.0 4892.6 4898.3 4912.4 5917.3 6096.1 6264.6 6291.4 6315.6 4871.2 4974.9 4993.7 5012.2 5030.1 5105.8 5107.6 5124.4 5124.9 5126.3 5375.7 5427.5 5444.5 5483.6 5486.5 5907.1 5910.4 5922.9 5943.6 5946.3 1748.8 1764.7 1777.0 1781.0 1788.3 1333.7 1343.9 1355.2 1356.4 1375.6 5558.5 5702.7 5703.2 5714.8 5741.6 6076.8 6136.5 6136.5 6143.1 6176.5 5479.9 5694.7 5722.7 5727.1 5774.2 3229.9 3248.4 3251.6 3266.9 3271.2 6077.4 6350.4 6397.3 6412.7 6434.0 6308.1 6340.3 6349.8 6350.0 6366.9 6455.4 6462.4 6480.5 6484.0 6497.5 5986.1 6151.3 6175.2 6201.4 6226.4 5754.5 6095.4 6095.4 6105.8 6107.3 6190.6 6266.6 6447.1 6456.6 6476.5 4997.9 5063.5 5065.7 5066.2 5074.4 6026.5 6167.4 6176.4 6213.9 6238.8 5974.3 5974.6 5980.6 5982.2 5983.6 5793.3 5975.3 5981.7 6012.3 6014.8 5949.6 5984.0 6002.7 6020.4 6024.2 6000.9 6015.2 6023.8 6061.3 6066.0 5676.0 5830.2 5854.8 5859.0 5874.4 06:14:45 INFO - PID 14238 | 06:14:45 INFO - PID 14238 | [#5] yui.html Cycles:180 Average:17352.09 Median:22024.94 stddev:20784.83 (94.4%) stddev-sans-first:20805.96 06:14:45 INFO - PID 14238 | Values: 795.7 803.0 847.9 858.8 864.5 16158.2 16351.7 16383.9 16387.4 16417.6 14730.0 14830.6 14865.8 14921.1 14921.4 823.7 833.2 850.0 856.6 858.6 857.4 858.5 860.5 860.7 861.1 899.6 899.7 899.8 899.9 900.4 892.3 894.2 895.5 895.6 895.7 653.3 654.8 655.6 656.3 656.8 16227.2 16321.9 16412.8 16842.8 16863.5 15969.8 16257.7 16522.0 16648.8 16737.9 12169.2 12478.3 12615.6 12967.4 13385.2 3837.8 3837.9 3850.1 3931.0 4029.5 68240.7 85730.6 87981.6 89328.6 90475.5 12612.7 12724.5 12780.8 12827.7 12946.3 25508.4 26094.3 26137.3 26337.7 26404.7 8737.5 8772.4 8779.6 8869.7 8957.5 4478.7 4483.2 4546.5 4575.1 4584.4 3409.5 3530.9 3532.2 3537.7 3538.8 572.0 576.4 587.1 594.7 596.1 735.6 769.7 805.3 813.6 863.8 10191.4 10661.4 10809.9 10945.9 11320.9 635.6 654.9 685.1 693.5 815.4 4930.9 5095.8 5148.4 5150.8 5174.5 55710.4 57433.4 57903.0 63546.0 80037.6 61301.0 61875.4 62215.0 62667.7 66316.3 57353.0 58342.0 58387.7 58467.7 58587.1 10571.3 11021.1 11158.6 11205.4 11282.7 39815.2 40020.4 40859.8 41170.0 41816.0 46833.0 46899.3 47560.3 47583.3 49414.3 13449.3 13529.6 13591.0 13783.4 14009.2 15487.9 15537.8 15603.8 15980.0 16034.6 13398.6 14605.9 14861.8 15682.6 15689.8 13885.2 14454.1 14592.9 14878.9 15109.7 14197.1 14769.8 14892.5 14916.3 15290.8 11300.5 11565.1 11957.8 12139.2 12339.9 10981.2 11906.3 12122.4 12155.7 12213.8 06:14:45 INFO - PID 14238 | -------- Summary: end -------- 06:14:45 INFO - PID 14238 | 06:14:46 INFO - TEST-INFO | 14238: exit 0 06:14:46 INFO - TEST-OK | dromaeo_css | took 1116071ms 06:14:46 INFO - TEST-START | kraken 06:14:46 INFO - operating with platform_type : linux_ 06:14:46 INFO - Initialising browser for kraken test... 06:14:46 INFO - TEST-INFO | started process 14564 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIZRWQK/profile http://localhost:42063/getInfo.html) 06:14:50 INFO - PID 14564 | __metrics Screen width/height:1600/1200 06:14:50 INFO - PID 14564 | colorDepth:24 06:14:50 INFO - PID 14564 | Browser inner width/height: 1024/697 06:14:50 INFO - PID 14564 | __metrics 06:14:50 INFO - PID 14564 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:14:50 INFO - PID 14564 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:14:51 INFO - TEST-INFO | 14564: exit 0 06:14:51 INFO - Browser initialized. 06:14:51 INFO - Running cycle 1/1 for kraken test... 06:14:51 INFO - TEST-INFO | started process 14659 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIZRWQK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:15:03 INFO - PID 14659 | RSS: Main: 145035264 06:15:03 INFO - PID 14659 | 06:15:06 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42063/tests/kraken/driver.html?testName=audio-beat-detection) 06:15:07 INFO - PID 14659 | RSS: Main: 199581696 06:15:07 INFO - PID 14659 | 06:15:09 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42063/tests/kraken/driver.html?testName=audio-dft) 06:15:10 INFO - PID 14659 | RSS: Main: 179798016 06:15:10 INFO - PID 14659 | 06:15:13 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42063/tests/kraken/driver.html?testName=audio-fft) 06:15:13 INFO - PID 14659 | RSS: Main: 261562368 06:15:13 INFO - PID 14659 | 06:15:16 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42063/tests/kraken/driver.html?testName=audio-oscillator) 06:15:16 INFO - PID 14659 | RSS: Main: 171454464 06:15:16 INFO - PID 14659 | 06:15:18 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42063/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:15:19 INFO - PID 14659 | RSS: Main: 176353280 06:15:19 INFO - PID 14659 | 06:15:22 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42063/tests/kraken/driver.html?testName=imaging-darkroom) 06:15:23 INFO - PID 14659 | RSS: Main: 214880256 06:15:23 INFO - PID 14659 | 06:15:26 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42063/tests/kraken/driver.html?testName=imaging-desaturate) 06:15:27 INFO - PID 14659 | RSS: Main: 231333888 06:15:27 INFO - PID 14659 | 06:15:29 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42063/tests/kraken/driver.html?testName=json-parse-financial) 06:15:30 INFO - PID 14659 | RSS: Main: 244903936 06:15:30 INFO - PID 14659 | 06:15:31 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42063/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:15:32 INFO - PID 14659 | RSS: Main: 178315264 06:15:32 INFO - PID 14659 | 06:15:34 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:15:34 INFO - PID 14659 | RSS: Main: 185446400 06:15:34 INFO - PID 14659 | 06:15:36 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:15:37 INFO - PID 14659 | RSS: Main: 193536000 06:15:37 INFO - PID 14659 | 06:15:40 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:15:40 INFO - PID 14659 | RSS: Main: 202907648 06:15:40 INFO - PID 14659 | 06:15:43 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:44 INFO - PID 14659 | RSS: Main: 199225344 06:15:44 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | Cycle 1(1): loaded http://localhost:42063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42063/tests/kraken/driver.html?testName=ai-astar) 06:15:46 INFO - PID 14659 | RSS: Main: 267743232 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | __start_tp_report 06:15:46 INFO - PID 14659 | _x_x_mozilla_page_load 06:15:46 INFO - PID 14659 | _x_x_mozilla_page_load_details 06:15:46 INFO - PID 14659 | |i|pagename|runs| 06:15:46 INFO - PID 14659 | |0;ai-astar;113;114;114;114;113;114;114;112;114;113 06:15:46 INFO - PID 14659 | |1;audio-beat-detection;148;122;116;178;123;124;120;117;146;117 06:15:46 INFO - PID 14659 | |2;audio-dft;162;161;162;161;161;161;161;161;162;163 06:15:46 INFO - PID 14659 | |3;audio-fft;73;73;74;115;75;75;74;74;92;77 06:15:46 INFO - PID 14659 | |4;audio-oscillator;83;84;116;83;83;84;103;83;83;110 06:15:46 INFO - PID 14659 | |5;imaging-gaussian-blur;112;113;113;114;114;113;113;113;114;113 06:15:46 INFO - PID 14659 | |6;imaging-darkroom;147;150;148;147;147;147;147;148;147;146 06:15:46 INFO - PID 14659 | |7;imaging-desaturate;95;94;94;94;94;94;94;94;95;94 06:15:46 INFO - PID 14659 | |8;json-parse-financial;57;56;56;57;57;57;58;57;57;57 06:15:46 INFO - PID 14659 | |9;json-stringify-tinderbox;56;56;56;56;57;57;56;56;56;56 06:15:46 INFO - PID 14659 | |10;stanford-crypto-aes;80;80;79;79;78;80;78;78;79;80 06:15:46 INFO - PID 14659 | |11;stanford-crypto-ccm;135;134;135;135;125;124;125;130;179;144 06:15:46 INFO - PID 14659 | |12;stanford-crypto-pbkdf2;182;187;186;176;183;183;190;183;194;180 06:15:46 INFO - PID 14659 | |13;stanford-crypto-sha256-iterative;66;63;65;64;66;66;64;65;67;64 06:15:46 INFO - PID 14659 | __end_tp_report 06:15:46 INFO - PID 14659 | __start_cc_report 06:15:46 INFO - PID 14659 | _x_x_mozilla_cycle_collect,1725 06:15:46 INFO - PID 14659 | __end_cc_report 06:15:46 INFO - PID 14659 | __startTimestamp1489583746426__endTimestamp 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | ------- Summary: start ------- 06:15:46 INFO - PID 14659 | Number of tests: 14 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:0.71 (0.6%) stddev-sans-first:0.73 06:15:46 INFO - PID 14659 | Values: 113.0 114.0 114.0 114.0 113.0 114.0 114.0 112.0 114.0 113.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:123.50 stddev:20.15 (16.3%) stddev-sans-first:20.43 06:15:46 INFO - PID 14659 | Values: 148.0 122.0 116.0 178.0 123.0 124.0 120.0 117.0 146.0 117.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 06:15:46 INFO - PID 14659 | Values: 162.0 161.0 162.0 161.0 161.0 161.0 161.0 161.0 162.0 163.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#3] audio-fft Cycles:10 Average:80.20 Median:74.50 stddev:13.47 (18.1%) stddev-sans-first:14.04 06:15:46 INFO - PID 14659 | Values: 73.0 73.0 74.0 115.0 75.0 75.0 74.0 74.0 92.0 77.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:83.00 stddev:13.11 (15.8%) stddev-sans-first:13.57 06:15:46 INFO - PID 14659 | Values: 83.0 84.0 116.0 83.0 83.0 84.0 103.0 83.0 83.0 110.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.63 (0.6%) stddev-sans-first:0.50 06:15:46 INFO - PID 14659 | Values: 112.0 113.0 113.0 114.0 114.0 113.0 113.0 113.0 114.0 113.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 06:15:46 INFO - PID 14659 | Values: 147.0 150.0 148.0 147.0 147.0 147.0 147.0 148.0 147.0 146.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 06:15:46 INFO - PID 14659 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 06:15:46 INFO - PID 14659 | Values: 57.0 56.0 56.0 57.0 57.0 57.0 58.0 57.0 57.0 57.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#9] json-stringify-tinderbox Cycles:10 Average:56.20 Median:56.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 06:15:46 INFO - PID 14659 | Values: 56.0 56.0 56.0 56.0 57.0 57.0 56.0 56.0 56.0 56.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.50 stddev:0.88 (1.1%) stddev-sans-first:0.87 06:15:46 INFO - PID 14659 | Values: 80.0 80.0 79.0 79.0 78.0 80.0 78.0 78.0 79.0 80.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:135.00 stddev:16.12 (11.9%) stddev-sans-first:17.09 06:15:46 INFO - PID 14659 | Values: 135.0 134.0 135.0 135.0 125.0 124.0 125.0 130.0 179.0 144.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:184.50 stddev:5.10 (2.8%) stddev-sans-first:5.34 06:15:46 INFO - PID 14659 | Values: 182.0 187.0 186.0 176.0 183.0 183.0 190.0 183.0 194.0 180.0 06:15:46 INFO - PID 14659 | 06:15:46 INFO - PID 14659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:1.25 (1.9%) stddev-sans-first:1.27 06:15:46 INFO - PID 14659 | Values: 66.0 63.0 65.0 64.0 66.0 66.0 64.0 65.0 67.0 64.0 06:15:46 INFO - PID 14659 | -------- Summary: end -------- 06:15:46 INFO - PID 14659 | 06:15:47 INFO - TEST-INFO | 14659: exit 0 06:15:47 INFO - TEST-OK | kraken | took 61183ms 06:15:47 INFO - SUITE-END | took 1177s 06:15:47 INFO - Completed test suite (00:19:37) 06:15:47 INFO - javascript benchmark 06:15:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7602.257361780906, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3198.9680103198966, 3223.967760322396, 3256.023193042088, 3260.136063943057, 3279.9672003279975, 5287.841364759054, 5392.757325920334, 5446.53704435123, 5482.862928426786, 5502.202180683798, 5485.4788795064505, 5506.256655351526, 5527.364353099395, 5535.308086489189, 5570.805021824237, 4803.039392121572, 4833.226683730604, 4879.463259041511, 4950.455449900508, 4983.401991760976, 4920.557149856512, 4936.827211047614, 4987.700737955734, 4988.625853061017, 5026.0, 4798.880027999293, 4805.591524720403, 4849.3453383793085, 4852.757362131879, 4909.680870743408, 4802.663813533054, 4867.415910090776, 4868.099401610713, 4869.293952376921, 4882.145624515695, 4754.690945088558, 4875.073735990186, 4891.584215341701, 4923.458419573844, 4957.82647607332, 5422.1053526168525, 5432.891342173174, 5450.509454149106, 5473.2063850741815, 5508.0, 5712.65724056558, 5804.100364443518, 5898.14476900847, 5912.201852749867, 5921.970390148064, 5821.50517206038, 5829.979753543113, 5902.085176797603, 5907.379725128842, 5922.674252916088, 5521.309836270466, 5531.059719847555, 5531.446855314517, 5566.276384070045, 5579.6931168785295, 6009.06859436788, 6061.4544690977145, 6068.362821903725, 6068.726907289182, 6074.574779765462, 5556.666600004012, 5629.577781666391, 5631.465010823965, 5801.332846722681, 5807.216025836543, 5782.566307526952, 5802.6808525531505, 5839.007368747323, 5839.795607153729, 5844.766209351664, 5872.618279811799, 5874.941250587525, 5875.500582450454, 5880.676562789087, 5893.499052580493, 5689.431056894277, 5771.769129234867, 5872.530197584183, 5885.793997210077, 5888.735006924699, 4522.276435770261, 4535.478419981679, 4545.2954792007295, 4550.704204226715, 4595.2188128018315, 4062.6343629072935, 4069.348904175377, 4076.9388459173138, 4081.4898137732785, 4122.3404255319, 5571.637843540238, 5576.470235327637, 5577.776888924479, 5579.637323574036, 5599.272094627672, 5755.625884317506, 5821.18503409523, 5850.824475265749, 5901.940980590139, 5908.793192238251, 5562.137868630369, 5568.0, 5570.944290557042, 5573.108302671553, 5600.6919619420505, 5014.749262536859, 5060.645754797202, 5067.454426399983, 5088.77100530477, 5114.977004599095, 5888.175655408247, 6056.636601803906, 6110.3461303294, 6159.076138579249, 6177.4749146322165, 5913.91129133072, 5916.053431450947, 5919.141724450017, 5946.28644562638, 5971.940280597139, 6153.169322141633, 6154.599951003171, 6155.692215389302, 6156.969215153896, 6159.846003849761, 5587.664740115606, 5652.491275785199, 5795.478406943395, 5829.621074630136, 5833.79581714638, 5902.203202567598, 5956.285245770535, 5964.343922168471, 5996.490298324691, 6002.249718785152, 5734.0, 5782.3639399666845, 5783.958887400308, 5788.363280039276, 5796.739146738322, 5603.159526070959, 5641.548676105819, 5646.576506762091, 5652.858678532905, 5778.219940308005, 5732.971335143297, 5772.307323121252, 5803.564732644984, 5818.981678206213, 5838.153467747238, 5711.143328500758, 5728.255326807655, 5732.34078081026, 5733.082706766897, 5738.942610573841, 5476.972615136899, 5578.832635020955, 5579.163125531203, 5583.246261754611, 5586.190002449704], "value": 5405.198462795684, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.824543864034, 753.5629334985708, 777.1490218211059, 824.5671022713071, 839.4333824668347, 28937.855310723444, 36294.45558316627, 36795.44806827893, 36903.261934761285, 36961.26077478449, 38673.22653546927, 39134.21731565374, 39137.804310978405, 39146.8042659787, 39187.0, 7022.438204943618, 7187.784366468988, 8623.396362254645, 8823.749943764331, 8831.072737362581, 9822.0, 10040.89959100407, 10051.798964020716, 10052.145567626723, 10059.899401006007, 10322.122619577307, 10444.791104177912, 10445.164386849034, 10452.738681532985, 10457.686269411892, 10259.538320775582, 10373.533191006423, 10377.377357358508, 10377.688669339932, 10382.532786024647, 9975.0, 10047.799044019152, 10051.447170405698, 10056.0, 10065.748356291078, 40506.594934050576, 40856.38715419271, 40880.0, 40885.0, 40979.0, 3888.2612303662213, 3940.6650434713083, 3943.0, 3970.047188674726, 3983.780892050936, 27746.722532774616, 27928.30179245535, 28261.0, 28310.292242693893, 29481.0, 4156.667466602665, 4169.958300417018, 4176.227397931392, 4176.728512646699, 4264.360345948102, 208265.0, 209062.0, 209701.0, 210551.0, 211442.0, 49078.0, 49151.75424122928, 49225.50774492209, 49284.50715492871, 49408.25887611689, 50807.49192508101, 50835.0, 50920.23619645708, 50988.74505627448, 51040.234396484084, 13620.659483512793, 13884.652883677989, 13886.652833679036, 13892.0, 13911.373988170557, 8840.778980525538, 9021.413608115452, 10410.791784164274, 11158.776824463464, 11159.609413670645, 12456.190347627375, 12510.624681259576, 12580.685482862818, 12611.369431528386, 12783.977281817619, 5091.8981620367385, 5329.333833270841, 5344.385395679469, 5376.327959005124, 5383.246345511631, 918.5453200665713, 938.9624415023459, 946.2240962410895, 950.3300173377752, 961.110972350573, 24133.276001719976, 26015.66678830835, 26273.868630657107, 26368.0, 26569.46861062768, 1004.4425343934187, 1010.7119470950744, 1017.8982101789762, 1022.4683164754287, 1025.887152413234, 13003.804942925864, 13760.793588096187, 13866.237356945465, 14068.648283792782, 14150.0, 214396.0, 217249.0, 217617.0, 217902.0, 292620.0, 49464.752676237105, 49596.25605615919, 49752.25371619429, 49753.25370119451, 49797.253041204414, 1333.2134040915905, 1364.7884577890443, 1384.8199734034713, 1385.515069725588, 1388.3266615691382, 25173.74826251714, 25545.744542554337, 27733.890644373998, 28024.159275221777, 28985.855070725356, 41563.7921810389, 41609.37585936274, 41613.0, 41664.58335416607, 41715.7914210427, 234784.0, 235420.0, 235722.0, 235728.0, 236068.0, 29760.10719678413, 30253.394932101673, 30428.087157385315, 30963.380732385678, 30986.225344366572, 30430.0, 32889.50665739968, 37928.0, 38440.6155938437, 39043.0, 31851.20371990719, 32367.676323236465, 32379.35241295114, 32624.510632341007, 33334.33331333311, 31539.52690709595, 32178.678213218504, 32328.515072273465, 32952.0, 33460.33079338351, 33879.830600846835, 34112.48831267583, 34129.0, 34190.31619367649, 36094.63905360913, 31029.8448507756, 31338.529922050733, 32098.0, 32220.35559288848, 32226.516602250515, 25154.37114072163, 25221.74778252194, 25449.491010180063, 25557.872210639573, 25597.23208303754], "value": 17344.802991098615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.337626678648, 429.86459265331416, 440.3416891746837, 442.84057739213887, 461.156084365611, 18703.5324116897, 19912.40262792117, 20511.948805119482, 20651.277205297818, 20707.275245366414, 19753.407397778054, 21608.56782864342, 21840.126394944222, 21893.67159492609, 22109.226177083805, 6474.3525647435235, 6834.1457317835275, 6834.2482326944, 6835.863282734343, 6852.897206541906, 6380.298167201604, 6735.831604209885, 6770.763023294186, 6774.119364482611, 6779.389854913057, 6531.150950376445, 6751.763688270911, 6770.086038384804, 6776.356246156632, 6779.661016949158, 6772.864542709143, 6826.24911259761, 6834.0, 6835.07726456927, 6838.384545390939, 6445.613263204223, 6461.095446637474, 6550.606963582152, 6563.54055216135, 6564.573302735307, 24510.264692059267, 24612.50774984508, 24744.25767226986, 24770.50458990828, 24830.379240518952, 2864.2266588021325, 2945.190072729995, 3009.8796048158047, 3019.1999120233154, 3060.32672811981, 15186.392544298325, 15860.60348491297, 16094.275757590936, 16199.919000404923, 16820.66358672832, 2053.5009443106464, 2074.5643414882743, 2084.666453367454, 2100.0549752611387, 2135.572885422906, 107685.0, 107841.46079269632, 108700.0, 109244.0, 109443.0, 17936.282548698167, 18268.0, 18361.54096147607, 18481.815181848277, 18501.0, 32741.69033238644, 32758.18104547358, 32834.835825821196, 32893.3421331572, 33105.337893242, 9107.180353768194, 9110.86333704995, 9192.63229470827, 9402.200812930978, 9441.0, 7276.163241227327, 7319.085114360705, 7507.587082710504, 7516.962415187889, 7537.0, 9553.856692149624, 9650.0, 9792.216622670312, 9899.0, 10112.898871011195, 3438.621751607321, 3632.055665526982, 3798.924021519554, 3995.7003224757677, 4007.979960100181, 1118.6743708705048, 1140.948657310423, 1142.3378983578873, 1165.1901928159848, 1181.1199317707487, 22158.781267030146, 22323.011833344142, 22607.660885086738, 22614.26864567361, 22630.66054009191, 1125.5104029747085, 1139.2879450343535, 1142.497301187475, 1157.7337212441187, 1170.0814860334597, 12206.267623942593, 12218.511259549696, 12571.08590275345, 12629.2422454653, 13444.596662100153, 677798.0, 679044.0, 684169.0, 687705.0, 694418.0, 82468.58765706133, 83310.0, 92665.07334926564, 94672.52663736638, 109486.0, 1318.0773458579033, 1365.0717512091874, 1391.325207274471, 1394.3655636685285, 1410.3441899516774, 20146.294879679142, 20317.79682203159, 20817.0, 20992.895035524725, 21027.369178924655, 50949.7452512735, 51152.23271650854, 51178.0, 51952.0, 52612.73693631507, 68728.0, 68784.0, 69218.30781692118, 69456.305436947, 70821.0, 11735.765284694087, 11810.586629467927, 12101.0, 12197.695057623276, 12317.568885088978, 14301.28493575338, 14595.92702036483, 14628.634284142981, 14634.926825365805, 14761.188134652697, 12052.758944820878, 12069.396530173632, 12106.394680266128, 12206.633800985985, 12505.874001419963, 12269.631911042683, 12738.745225095261, 12744.553940612033, 12799.744005120032, 13149.408276627766, 12516.31160286193, 12518.749625007631, 12589.118761686594, 12600.810987835373, 12606.621801345975, 12043.277403355827, 12213.877861221274, 12233.632991010283, 12383.257004579755, 12527.310997895203, 12869.420876060787, 12910.67723306925, 12912.289824059766, 12959.41682624303, 12980.805287920877], "value": 11467.377556623298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.97961530577, 1479.7558402863528, 1502.3164460170624, 1503.5940296120048, 1582.857542821146, 8824.161704638058, 10502.527386267617, 10794.568217271319, 10851.565937362506, 10929.617463388782, 10559.208059395538, 10644.99999999998, 10649.148068154549, 10658.307210031362, 10970.780584388307, 1825.9908700456479, 1880.699088145897, 2209.524952135295, 2243.0467051503047, 2245.6856040154394, 2167.0, 2246.1801442473543, 2248.4603695113137, 2253.425376528991, 2253.6394176931694, 2213.5351576169023, 2225.198928385788, 2237.2393386248673, 2241.1707668162803, 2242.955140897181, 2182.7598964113936, 2191.485001024758, 2191.835612329082, 2193.616117179487, 2197.1321328075483, 2062.453449835795, 2181.029441898356, 2181.476445653048, 2186.497105665706, 2188.081005977477, 10840.67477975662, 10904.563817447293, 10969.23215374924, 10970.286931349438, 10974.890251097546, 5342.519173274385, 5528.778848846041, 5534.418886016951, 5534.446555344473, 5538.113901775737, 3145.9685403145904, 3253.4631785755323, 3287.211069343364, 3290.424175769255, 3326.6340702522702, 1184.7926612842718, 1235.6849003504049, 1237.3874931908672, 1271.319843883527, 1274.0635632809876, 15391.615209619737, 15490.9225453872, 15500.0, 15523.146226957513, 15523.534293971199, 4284.400183974246, 4435.334699795057, 4509.954900451019, 4528.45658520973, 4553.499115097403, 13223.801642975362, 13253.337333133304, 13273.0, 13281.933590331986, 13288.601341959757, 5534.557235421156, 5553.805616803393, 5570.025245582007, 5573.944260557342, 5578.274824272899, 3994.0, 4477.888052798641, 4883.072216278895, 4965.90068198634, 5043.420006699276, 3604.2070744436182, 3721.8697345592773, 3723.404255319136, 3798.1454172810895, 3820.8471661133244, 1030.9896901031043, 1035.089121573026, 1043.4209013997154, 1048.4705223861902, 1058.2433560004633, 956.2302346610905, 961.6009356117133, 967.7870868408938, 988.6094992477931, 995.2187532786728, 6412.665413984701, 6620.304867988888, 6636.037774522667, 6637.867242655217, 6808.2170550386345, 957.5116690487902, 974.4835237324229, 987.2200961240683, 1017.2828156149897, 1025.7230547752065, 4101.282275601818, 4240.575942405735, 4280.186764514732, 4330.870073897788, 4404.537523560044, 13167.868321316855, 13583.049186557044, 13584.049116561746, 13614.0, 13615.31923403826, 13224.867751322556, 13351.799723004162, 13425.932870335586, 13476.730465390638, 13608.183508989492, 9802.803943921082, 9829.950850245703, 9875.654352097643, 9928.702138935989, 9985.800283994426, 6434.742610295723, 6439.452646525097, 6481.837954051187, 6539.869202615826, 6554.594320939798, 11697.473613687234, 11706.35615041181, 11900.583479578174, 11934.164608477324, 12019.699507512381, 12037.277763334228, 12310.26138431697, 12311.0, 12321.815172772236, 12407.317597532223, 7780.92219077802, 7868.449208555347, 9094.545272736468, 9211.078892110736, 9239.953800230956, 10081.294309398272, 10136.695899123039, 10215.897841021495, 10237.74405639865, 10265.948670256601, 8914.643414263357, 8999.955000224956, 9017.684381046633, 9118.270538356783, 9213.0, 8657.783555411164, 8679.0, 8856.512891791013, 8867.467951922905, 9090.90909090927, 7673.884891726517, 7695.57674327895, 7768.067831860213, 7777.222277772177, 7919.445638805229, 5502.119660854244, 5599.888002239851, 5712.571557133148, 5742.2150257126095, 5797.623154494944, 5752.583914755729, 5827.825165245049, 5840.445157710011, 6023.728932198149, 6033.879322413615], "value": 5008.725735028428, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.10984273968216, 413.8737685006073, 419.3039554339796, 421.9810108545115, 431.44774688398843, 5674.773009079637, 5719.227904232927, 5723.627964182325, 5726.68503232322, 5781.739821708023, 5278.894422111555, 5554.611177217596, 5588.463172627519, 5615.494605485505, 5648.887022259552, 4436.268015777393, 4583.541645835423, 4629.911970686886, 4632.976835115836, 4650.697704649196, 4585.679002469828, 4604.792784324697, 4608.216603177468, 4626.097910907374, 4629.259318509039, 4594.1271158479785, 4607.35497030416, 4607.976960115195, 4627.8611641650805, 4633.49031606523, 4497.235469970113, 4591.334256532784, 4628.0, 4628.583427491509, 4642.767861606923, 4309.396684464177, 4314.741115533078, 4318.352247162919, 4330.891727706832, 4331.7617531035785, 5488.780448782044, 5515.586331025149, 5530.11518157097, 5534.557235421197, 5552.7778888844805, 5282.577393808486, 5485.835424937259, 5499.175123731432, 5504.376209018349, 5538.363088244863, 5929.1995580596495, 5952.494038006775, 5966.090850927705, 5978.790742323998, 5996.100584912255, 4782.808687652489, 4890.975545122287, 4892.559669629715, 4898.265260210961, 4912.435069966964, 5917.289925208959, 6096.116063170815, 6264.624122552661, 6291.43377096059, 6315.557910946236, 4871.171900776876, 4974.850754477371, 4993.700377977333, 5012.248162775612, 5030.094582975026, 5105.770240339194, 5107.565856902131, 5124.436312005676, 5124.897502049938, 5126.307948426915, 5375.731213439312, 5427.511523962862, 5444.537214336747, 5483.643563168461, 5486.478784515464, 5907.113932910098, 5910.438508341701, 5922.911156332659, 5943.613665111754, 5946.256717910262, 1748.7988881278816, 1764.7176451767655, 1777.0315178227897, 1781.0026385224057, 1788.2936240185313, 1333.7132516508802, 1343.8857697095661, 1355.1801160298076, 1356.3964036004081, 1375.6354566040006, 5558.471945165203, 5702.714864256771, 5703.201551782754, 5714.828555143353, 5741.626794258444, 6076.848078798065, 6136.453371850721, 6136.539759518054, 6143.139960405547, 6176.505879529627, 5479.917801232985, 5694.686792226385, 5722.7424765885635, 5727.140928860621, 5774.220480235114, 3229.870805167814, 3248.447763880146, 3251.5772949516413, 3266.918327041843, 3271.165852707544, 6077.392260773976, 6350.364963503613, 6397.296297407282, 6412.7495138448485, 6433.967830160913, 6308.148399965946, 6340.302566717657, 6349.809505714836, 6349.9682501587195, 6366.872662546723, 6455.419012288822, 6462.386073323027, 6480.4815614750705, 6483.957553370673, 6497.5126865484335, 5986.132010858489, 6151.2618485781995, 6175.166352542529, 6201.441870231701, 6226.40849119333, 5754.4533269339345, 6095.35998720128, 6095.359987201457, 6105.786297479567, 6107.297660769068, 6190.5547308811865, 6266.561340706106, 6447.129637498878, 6456.644884531396, 6476.5466417350335, 4997.900041999213, 5063.493650634907, 5065.746712664279, 5066.164082926276, 5074.3657042869645, 6026.517878569616, 6167.383261673796, 6176.382361763967, 6213.937860621336, 6238.750449982132, 5974.2830860297045, 5974.581779275409, 5980.581359304807, 5982.162497250475, 5983.551233657406, 5793.333766616893, 5975.312839023568, 5981.671008094597, 6012.3386427493815, 6014.789482368096, 5949.583529153091, 5983.978091064159, 6002.669853158118, 6020.367861374492, 6024.156618073385, 6000.939990600213, 6015.248093988252, 6023.759049637965, 6061.333253342041, 6066.0, 5675.987430889867, 5830.2129212555765, 5854.824355269348, 5859.0, 5874.383189765013], "value": 4763.9379633601075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.6936579416927, 802.9842249554315, 847.8770578266151, 858.823941092076, 864.4742030316488, 16158.192090395478, 16351.672966540662, 16383.91808040961, 16387.4264400746, 16417.589560260985, 14730.0, 14830.55508334749, 14865.777013344808, 14921.104733715985, 14921.403143874231, 823.7199352220244, 833.1585099049972, 849.9575021248913, 856.5717141429285, 858.6093327535988, 857.3526987124743, 858.5063588436643, 860.535310932099, 860.6514361683528, 861.1044513705739, 899.5996781432265, 899.685110211424, 899.7570655922897, 899.883015208022, 900.3967341880956, 892.2638822971074, 894.2130924786212, 895.4671970177734, 895.5701263393574, 895.7223260789111, 653.3335997282793, 654.8330175805187, 655.5673255651295, 656.3141517114626, 656.8423578341211, 16227.188640568042, 16321.918390408091, 16412.83587164137, 16842.831571684248, 16863.494095177164, 15969.760453593206, 16257.674846503121, 16522.0, 16648.80171902336, 16737.91631041837, 12169.191367706359, 12478.313692746895, 12615.621531354074, 12967.351632418342, 13385.19688818674, 3837.81375315608, 3837.9424308635394, 3850.1144736710435, 3930.960690393088, 4029.476168098158, 68240.6587967062, 85730.57134714286, 87981.56009219977, 89328.55335723345, 90475.54762226147, 12612.684682882818, 12724.491020359266, 12780.809810804452, 12827.679308017374, 12946.287954162612, 25508.362290942874, 26094.34764130874, 26137.346566335615, 26337.73662263391, 26404.73595264061, 8737.475751454933, 8772.38593298463, 8779.648814047494, 8869.68956086534, 8957.462552246887, 4478.686491945533, 4483.170613436525, 4546.477155127137, 4575.130725162208, 4584.4498660161, 3409.5397121388296, 3530.876419325363, 3532.187596852687, 3537.681608655182, 3538.840752166159, 571.9513841323534, 576.4264556766045, 587.1105275507609, 594.7353427724621, 596.1296507099684, 735.63586024918, 769.7382889817488, 805.2631841864745, 813.6257321631997, 863.7797361672733, 10191.439470829027, 10661.360318380923, 10809.945950270197, 10945.890541094646, 11320.943395282971, 635.5678138865524, 654.9476041916636, 685.1298850459888, 693.517742247524, 815.3866856724303, 4930.929009815886, 5095.770690318945, 5148.3564554430695, 5150.819721309736, 5174.508421699932, 55710.44289557052, 57433.425665742805, 57903.0, 63546.0, 80037.59981200057, 61301.0, 61875.38124618786, 62215.0, 62667.686661566404, 66316.33683663102, 57353.0, 58342.0, 58387.70806145942, 58467.70766146142, 58587.12119318214, 10571.278695076438, 11021.118310534977, 11158.55365785392, 11205.383703896365, 11282.661520154408, 39815.20369592534, 40020.399694005195, 40859.79570102131, 41170.0, 41816.0, 46833.0, 46899.29651055305, 47560.2865957004, 47583.28625070696, 49414.25878611752, 13449.260290684106, 13529.59411217665, 13591.0, 13783.37974791157, 14009.159450433006, 15487.93368007819, 15537.76693349578, 15603.765943510629, 15980.041197528186, 16034.599135021717, 13398.598042058753, 14605.92697036508, 14861.777073344125, 15682.607934801721, 15689.843101568838, 13885.166889986633, 14454.132752034911, 14592.854071459149, 14878.925605372337, 15109.697806044038, 14197.077189982618, 14769.778453322993, 14892.478763243234, 14916.328765205808, 15290.77063844021, 11300.547978080785, 11565.059688701902, 11957.76084478323, 12139.150259482105, 12339.93830030844, 10981.176411768989, 11906.345151016778, 12122.393880306126, 12155.696107597381, 12213.816792748294], "value": 7524.555608174968, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1510.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 112.0, 114.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 116.0, 178.0, 123.0, 124.0, 120.0, 117.0, 146.0, 117.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 115.0, 75.0, 75.0, 74.0, 74.0, 92.0, 77.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 116.0, 83.0, 83.0, 84.0, 103.0, 83.0, 83.0, 110.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 146.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 57.0, 57.0, 57.0, 58.0, 57.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 57.0, 57.0, 56.0, 56.0, 56.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 79.0, 78.0, 80.0, 78.0, 78.0, 79.0, 80.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 135.0, 135.0, 125.0, 124.0, 125.0, 130.0, 179.0, 144.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 186.0, 176.0, 183.0, 183.0, 190.0, 183.0, 194.0, 180.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 65.0, 64.0, 66.0, 66.0, 64.0, 65.0, 67.0, 64.0], "value": 65.0, "unit": "score"}]}]} 06:15:47 INFO - Return code: 0 06:15:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:15:47 ERROR - # TBPL SUCCESS # 06:15:47 INFO - Running post-action listener: _resource_record_post_action 06:15:47 INFO - [mozharness: 2017-03-15 13:15:47.755930Z] Finished run-tests step (success) 06:15:47 INFO - Running post-run listener: _resource_record_post_run 06:15:47 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 368001024; Read time: 144; Write time: 256896 06:15:47 INFO - TinderboxPrint: CPU usage
12.9% 06:15:47 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 144 06:15:47 INFO - TinderboxPrint: I/O write bytes / time
368,001,024 / 256,896 06:15:47 INFO - TinderboxPrint: CPU idle
8,300.1 (87.1%) 06:15:47 INFO - TinderboxPrint: CPU user
1,185.0 (12.4%) 06:15:47 INFO - TinderboxPrint: Swap in / out
0 / 0 06:15:47 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 111292416; Read time: 68; Write time: 104160 06:15:47 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 256708608; Read time: 76; Write time: 152736 06:15:48 INFO - Running post-run listener: _upload_blobber_files 06:15:48 INFO - Blob upload gear active. 06:15:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:15:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:15:48 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:15:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:15:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:51 INFO - (blobuploader) - INFO - Done attempting. 06:15:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:15:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:15:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:54 INFO - (blobuploader) - INFO - Done attempting. 06:15:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:15:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:15:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:55 INFO - (blobuploader) - INFO - Done attempting. 06:15:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:15:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:15:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:56 INFO - (blobuploader) - INFO - Done attempting. 06:15:56 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:56 INFO - Return code: 0 06:15:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:56 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/913907c44a48932c55079d41224fe09e503f3d31a8147d61e4fb74d70944aaf707ccb6a56a70a15fb4b7d335cf0185141f966e44d3b3240f9e2c353bd52d2aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4da087905509add6a57d19143fdf960c587b6408f33cd3caa06e18c1b36c7a0f4a913fc5a75b9debe77c8b5e6476137c9af903b88ca3770b67f6e1b6f6ff2aa0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ae503e258363074f81f6ae8d6d3e354868ba7f5e2d49695ce9865d7c9044d2db9fe2e68ac8042f20d702778caab6716ea695dbea1f6c55a0d1fff580c6de68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02d93ea88171c87eb0abf6db4a2ea959bb906f144f541b333aae6fe5c99ebb61f9ab7ab26626727457095e7ddeff53c6c66c76097d01a3e08bf148de328516d0"} 06:15:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:56 INFO - Contents: 06:15:56 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/913907c44a48932c55079d41224fe09e503f3d31a8147d61e4fb74d70944aaf707ccb6a56a70a15fb4b7d335cf0185141f966e44d3b3240f9e2c353bd52d2aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4da087905509add6a57d19143fdf960c587b6408f33cd3caa06e18c1b36c7a0f4a913fc5a75b9debe77c8b5e6476137c9af903b88ca3770b67f6e1b6f6ff2aa0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ae503e258363074f81f6ae8d6d3e354868ba7f5e2d49695ce9865d7c9044d2db9fe2e68ac8042f20d702778caab6716ea695dbea1f6c55a0d1fff580c6de68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02d93ea88171c87eb0abf6db4a2ea959bb906f144f541b333aae6fe5c99ebb61f9ab7ab26626727457095e7ddeff53c6c66c76097d01a3e08bf148de328516d0"} 06:15:56 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:56 INFO - Copying logs to upload dir... 06:15:56 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.532720 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-15 06:15:55.996648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 06:15:55.999774) ========= 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=3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/913907c44a48932c55079d41224fe09e503f3d31a8147d61e4fb74d70944aaf707ccb6a56a70a15fb4b7d335cf0185141f966e44d3b3240f9e2c353bd52d2aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4da087905509add6a57d19143fdf960c587b6408f33cd3caa06e18c1b36c7a0f4a913fc5a75b9debe77c8b5e6476137c9af903b88ca3770b67f6e1b6f6ff2aa0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ae503e258363074f81f6ae8d6d3e354868ba7f5e2d49695ce9865d7c9044d2db9fe2e68ac8042f20d702778caab6716ea695dbea1f6c55a0d1fff580c6de68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02d93ea88171c87eb0abf6db4a2ea959bb906f144f541b333aae6fe5c99ebb61f9ab7ab26626727457095e7ddeff53c6c66c76097d01a3e08bf148de328516d0"} build_url:https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/ZigqRZ3ATR23emKsI6SIUg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/913907c44a48932c55079d41224fe09e503f3d31a8147d61e4fb74d70944aaf707ccb6a56a70a15fb4b7d335cf0185141f966e44d3b3240f9e2c353bd52d2aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4da087905509add6a57d19143fdf960c587b6408f33cd3caa06e18c1b36c7a0f4a913fc5a75b9debe77c8b5e6476137c9af903b88ca3770b67f6e1b6f6ff2aa0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ae503e258363074f81f6ae8d6d3e354868ba7f5e2d49695ce9865d7c9044d2db9fe2e68ac8042f20d702778caab6716ea695dbea1f6c55a0d1fff580c6de68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02d93ea88171c87eb0abf6db4a2ea959bb906f144f541b333aae6fe5c99ebb61f9ab7ab26626727457095e7ddeff53c6c66c76097d01a3e08bf148de328516d0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 06:15:56.024906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 06:15:56.025248) ========= 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=3a3620fb28aea1604ab609ef000001d2-1489582500.72650-570871093 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 06:15:56.108244) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 06:15:56.108575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 06:15:56.108888) ========= ========= Total master_lag: 0.14 =========