builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1489940374.66 results: success (0) revision: 1b9293be51637f841275541d8991314ca56561a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.662860) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.663331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.663610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.678219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.678517) ========= 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=20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-19 09:19:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2017-03-19 09:19:35 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111826 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.804076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.804513) ========= 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=20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016607 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:34.854265) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-19 09:19:34.854604) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b9293be51637f841275541d8991314ca56561a5 --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 1b9293be51637f841275541d8991314ca56561a5 --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=20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-19 09:19:35,251 truncating revision to first 12 chars 2017-03-19 09:19:35,251 Setting DEBUG logging. 2017-03-19 09:19:35,251 attempt 1/10 2017-03-19 09:19:35,251 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b9293be5163?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-19 09:19:36,583 unpacking tar archive at: mozilla-central-1b9293be5163/testing/mozharness/ program finished with exit code 0 elapsedTime=1.633593 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-19 09:19:36.501835) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:36.502219) ========= script_repo_revision: 1b9293be51637f841275541d8991314ca56561a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:36.502632) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:36.502954) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-19 09:19:36.512311) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-19 09:19:36.512637) ========= /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=20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:19:36 INFO - MultiFileLogger online at 20170319 09:19:36 in /builds/slave/test 09:19:36 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 09:19:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:19:37 INFO - {'append_to_log': False, 09:19:37 INFO - 'base_work_dir': '/builds/slave/test', 09:19:37 INFO - 'blob_upload_branch': 'Firefox', 09:19:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:19:37 INFO - 'branch': 'Firefox', 09:19:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:19:37 INFO - 'config_files': ('talos/linux_config.py',), 09:19:37 INFO - 'default_actions': ('clobber', 09:19:37 INFO - 'read-buildbot-config', 09:19:37 INFO - 'download-and-extract', 09:19:37 INFO - 'populate-webroot', 09:19:37 INFO - 'create-virtualenv', 09:19:37 INFO - 'install', 09:19:37 INFO - 'run-tests'), 09:19:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:19:37 INFO - 'download_minidump_stackwalk': True, 09:19:37 INFO - 'download_symbols': 'ondemand', 09:19:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:19:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:19:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:19:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:19:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:19:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:19:37 INFO - 'gecko_profile': False, 09:19:37 INFO - 'gecko_profile_interval': 0, 09:19:37 INFO - 'installer_path': 'installer.exe', 09:19:37 INFO - 'log_level': 'info', 09:19:37 INFO - 'log_name': 'talos', 09:19:37 INFO - 'log_to_console': True, 09:19:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:19:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:19:37 INFO - 'opt_config_files': (), 09:19:37 INFO - 'pip_index': False, 09:19:37 INFO - 'suite': 'dromaeojs', 09:19:37 INFO - 'system_bits': '32', 09:19:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:19:37 INFO - 'title': 'talos-linux64-ix-015', 09:19:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:19:37 INFO - 'use_talos_json': True, 09:19:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:19:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:19:37 INFO - 'work_dir': 'build'} 09:19:37 INFO - [mozharness: 2017-03-19 16:19:37.008981Z] Running clobber step. 09:19:37 INFO - Running pre-action listener: _resource_record_pre_action 09:19:37 INFO - Running main action method: clobber 09:19:37 INFO - rmtree: /builds/slave/test/build 09:19:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:19:38 INFO - Running post-action listener: _resource_record_post_action 09:19:38 INFO - [mozharness: 2017-03-19 16:19:38.481688Z] Finished clobber step (success) 09:19:38 INFO - [mozharness: 2017-03-19 16:19:38.481787Z] Running read-buildbot-config step. 09:19:38 INFO - Running pre-action listener: _resource_record_pre_action 09:19:38 INFO - Running main action method: read_buildbot_config 09:19:38 INFO - Using buildbot properties: 09:19:38 INFO - { 09:19:38 INFO - "project": "", 09:19:38 INFO - "product": "firefox", 09:19:38 INFO - "who": "archaeopteryx@coole-files.de", 09:19:38 INFO - "installer_path": "public/build/target.tar.bz2", 09:19:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:19:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:19:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:19:38 INFO - "stage_platform": "linux64-pgo", 09:19:38 INFO - "basedir": "/builds/slave/test", 09:19:38 INFO - "buildnumber": 83, 09:19:38 INFO - "platform": "ubuntu64_hw", 09:19:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:19:38 INFO - "slavebuilddir": "test", 09:19:38 INFO - "taskId": "GM3SnEQgQaONTOmRPKWxUQ", 09:19:38 INFO - "branch": "mozilla-central", 09:19:38 INFO - "script_repo_revision": "production", 09:19:38 INFO - "revision": "1b9293be51637f841275541d8991314ca56561a5", 09:19:38 INFO - "slavename": "talos-linux64-ix-015", 09:19:38 INFO - "repo_path": "mozilla-central" 09:19:38 INFO - } 09:19:38 INFO - Finding installer, test and symbols from parent task. 09:19:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GM3SnEQgQaONTOmRPKWxUQ'}, attempt #1 09:19:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GM3SnEQgQaONTOmRPKWxUQ'}, attempt #1 09:19:39 INFO - Task dependencies: KXjj8ZdbSimg6OfBP5ObJg 09:19:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg'}, attempt #1 09:19:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2 09:19:39 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/test_packages.json 09:19:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.crashreporter-symbols.zip 09:19:39 INFO - Running post-action listener: _resource_record_post_action 09:19:39 INFO - [mozharness: 2017-03-19 16:19:39.854071Z] Finished read-buildbot-config step (success) 09:19:39 INFO - [mozharness: 2017-03-19 16:19:39.854289Z] Running download-and-extract step. 09:19:39 INFO - Running pre-action listener: _resource_record_pre_action 09:19:39 INFO - Running main action method: download_and_extract 09:19:39 INFO - mkdir: /builds/slave/test/build/tests 09:19:39 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')]} 09:19:39 INFO - trying https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.test_packages.json 09:19:39 INFO - Downloading https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:19:40 INFO - Downloaded 986 bytes. 09:19:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:19:40 INFO - Using the following test package requirements: 09:19:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:19:40 INFO - u'common': [u'target.common.tests.zip'], 09:19:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:19:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:19:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:19:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:19:40 INFO - u'mozbase': [u'target.common.tests.zip'], 09:19:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:19:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:19:40 INFO - u'web-platform': [u'target.common.tests.zip', 09:19:40 INFO - u'target.web-platform.tests.zip'], 09:19:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:19:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:19:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.common.tests.zip 09:19:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:19:40 INFO - Fetch https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.common.tests.zip into memory 09:19:41 INFO - Expected file size: 60312666 09:19:41 INFO - Obtained file size: 60312666 09:19:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:19:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.common.tests.zip 09:19:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:19:51 INFO - Fetch https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.common.tests.zip into memory 09:19:52 INFO - Expected file size: 60312666 09:19:52 INFO - Obtained file size: 60312666 09:20:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.talos.tests.zip 09:20:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:20:03 INFO - Fetch https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.talos.tests.zip into memory 09:20:04 INFO - Expected file size: 14404171 09:20:04 INFO - Obtained file size: 14404171 09:20: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')]} 09:20:04 INFO - trying https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2 09:20:04 INFO - Downloading https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:20:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:20:05 INFO - Downloaded 67239067 bytes. 09:20:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2 09:20:05 INFO - mkdir: /builds/slave/test/properties 09:20:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:20:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:20:05 INFO - Contents: 09:20:05 INFO - build_url:https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2 09:20:05 INFO - Running post-action listener: _resource_record_post_action 09:20:05 INFO - Running post-action listener: set_extra_try_arguments 09:20:05 INFO - [mozharness: 2017-03-19 16:20:05.725412Z] Finished download-and-extract step (success) 09:20:05 INFO - [mozharness: 2017-03-19 16:20:05.725510Z] Running populate-webroot step. 09:20:05 INFO - Running pre-action listener: _resource_record_pre_action 09:20:05 INFO - Running main action method: populate_webroot 09:20:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:20:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:20:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:20:05 INFO - '--tppagecycles', 09:20:05 INFO - '7'], 09:20:05 INFO - 'tests': ['tsvgm']}}, 09:20:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:20:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:20:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:20:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:20:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['damp', 'tps']}, 09:20:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'tests': ['damp', 'tps']}, 09:20:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['dromaeo_dom']}, 09:20:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:20:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['basic_compositor_video']}, 09:20:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:20:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['a11yr', 09:20:05 INFO - 'ts_paint', 09:20:05 INFO - 'tpaint', 09:20:05 INFO - 'sessionrestore', 09:20:05 INFO - 'sessionrestore_no_auto_restore', 09:20:05 INFO - 'tabpaint']}, 09:20:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:20:05 INFO - 'ts_paint', 09:20:05 INFO - 'tpaint', 09:20:05 INFO - 'sessionrestore', 09:20:05 INFO - 'sessionrestore_no_auto_restore', 09:20:05 INFO - 'tabpaint']}, 09:20:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:20:05 INFO - 'ts_paint', 09:20:05 INFO - 'tpaint', 09:20:05 INFO - 'sessionrestore', 09:20:05 INFO - 'sessionrestore_no_auto_restore', 09:20:05 INFO - 'tabpaint']}, 09:20:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:20:05 INFO - 'ts_paint', 09:20:05 INFO - 'tpaint', 09:20:05 INFO - 'sessionrestore', 09:20:05 INFO - 'sessionrestore_no_auto_restore', 09:20:05 INFO - 'tabpaint']}, 09:20:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['a11yr', 09:20:05 INFO - 'ts_paint', 09:20:05 INFO - 'tpaint', 09:20:05 INFO - 'sessionrestore', 09:20:05 INFO - 'sessionrestore_no_auto_restore', 09:20:05 INFO - 'tabpaint']}, 09:20:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['a11yr', 09:20:05 INFO - 'ts_paint', 09:20:05 INFO - 'tpaint', 09:20:05 INFO - 'sessionrestore', 09:20:05 INFO - 'sessionrestore_no_auto_restore', 09:20:05 INFO - 'tabpaint']}, 09:20:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['tsvgx', 09:20:05 INFO - 'tsvgr_opacity', 09:20:05 INFO - 'tart', 09:20:05 INFO - 'tscrollx', 09:20:05 INFO - 'cart', 09:20:05 INFO - 'tsvg_static']}, 09:20:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:20:05 INFO - 'tsvgr_opacity', 09:20:05 INFO - 'tart', 09:20:05 INFO - 'tscrollx', 09:20:05 INFO - 'cart', 09:20:05 INFO - 'tsvg_static']}, 09:20:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'talos_options': ['--disable-e10s'], 09:20:05 INFO - 'tests': ['tp5o']}, 09:20:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:20:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'talos_options': ['--disable-e10s', 09:20:05 INFO - '--xperf_path', 09:20:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:05 INFO - 'tests': ['tp5n']}, 09:20:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:20:05 INFO - 'talos_options': ['--xperf_path', 09:20:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:05 INFO - 'tests': ['tp5n']}}, 09:20:05 INFO - 'talos.zip': {'path': '', 09:20:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:20:05 INFO - Running post-action listener: _resource_record_post_action 09:20:05 INFO - [mozharness: 2017-03-19 16:20:05.738728Z] Finished populate-webroot step (success) 09:20:05 INFO - [mozharness: 2017-03-19 16:20:05.738820Z] Running create-virtualenv step. 09:20:05 INFO - Running pre-action listener: _resource_record_pre_action 09:20:05 INFO - Running main action method: create_virtualenv 09:20:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:20:05 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 09:20:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:20:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:20:05 INFO - Using real prefix '/usr' 09:20:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:20:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:20:08 INFO - Installing pip.................done. 09:20:08 INFO - Return code: 0 09:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:20:08 INFO - Reading from file tmpfile_stdout 09:20:08 INFO - Output received: 09:20:08 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) 09:20:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:08 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:08 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 09:20:08 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 09:20:08 INFO - Using env: {'DISPLAY': ':0', 09:20:08 INFO - 'HOME': '/home/cltbld', 09:20:08 INFO - 'LANG': 'en_US.UTF-8', 09:20:08 INFO - 'LANGUAGE': 'en_US:en', 09:20:08 INFO - 'LOGNAME': 'cltbld', 09:20:08 INFO - 'MAIL': '/var/mail/cltbld', 09:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:08 INFO - 'MOZ_NO_REMOTE': '1', 09:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:08 INFO - 'NO_EM_RESTART': '1', 09:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:08 INFO - 'PWD': '/builds/slave/test', 09:20:08 INFO - 'SHELL': '/bin/bash', 09:20:08 INFO - 'SHLVL': '1', 09:20:08 INFO - 'TERM': 'linux', 09:20:08 INFO - 'TMOUT': '86400', 09:20:08 INFO - 'USER': 'cltbld', 09:20:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:08 INFO - '_': '/tools/buildbot/bin/python'} 09:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:08 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 09:20:08 INFO - Cleaning up... 09:20:08 INFO - Return code: 0 09:20:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:08 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:08 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 09:20:08 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 09:20:08 INFO - Using env: {'DISPLAY': ':0', 09:20:08 INFO - 'HOME': '/home/cltbld', 09:20:08 INFO - 'LANG': 'en_US.UTF-8', 09:20:08 INFO - 'LANGUAGE': 'en_US:en', 09:20:08 INFO - 'LOGNAME': 'cltbld', 09:20:08 INFO - 'MAIL': '/var/mail/cltbld', 09:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:08 INFO - 'MOZ_NO_REMOTE': '1', 09:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:08 INFO - 'NO_EM_RESTART': '1', 09:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:08 INFO - 'PWD': '/builds/slave/test', 09:20:08 INFO - 'SHELL': '/bin/bash', 09:20:08 INFO - 'SHLVL': '1', 09:20:08 INFO - 'TERM': 'linux', 09:20:08 INFO - 'TMOUT': '86400', 09:20:08 INFO - 'USER': 'cltbld', 09:20:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:08 INFO - '_': '/tools/buildbot/bin/python'} 09:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:08 INFO - Downloading/unpacking psutil>=3.1.1 09:20:08 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 09:20:08 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 09:20:08 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 09:20:08 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 09:20:08 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 09:20:08 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 09:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:20:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:11 INFO - Installing collected packages: psutil 09:20:11 INFO - Running setup.py install for psutil 09:20:11 INFO - building 'psutil._psutil_linux' extension 09:20:11 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 09:20:11 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 09:20:11 INFO - building 'psutil._psutil_posix' extension 09:20:11 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 09:20:11 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 09:20:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:11 INFO - Successfully installed psutil 09:20:11 INFO - Cleaning up... 09:20:11 INFO - Return code: 0 09:20:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:11 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:11 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 09:20:11 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 09:20:11 INFO - Using env: {'DISPLAY': ':0', 09:20:11 INFO - 'HOME': '/home/cltbld', 09:20:11 INFO - 'LANG': 'en_US.UTF-8', 09:20:11 INFO - 'LANGUAGE': 'en_US:en', 09:20:11 INFO - 'LOGNAME': 'cltbld', 09:20:11 INFO - 'MAIL': '/var/mail/cltbld', 09:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:11 INFO - 'MOZ_NO_REMOTE': '1', 09:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:11 INFO - 'NO_EM_RESTART': '1', 09:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:11 INFO - 'PWD': '/builds/slave/test', 09:20:11 INFO - 'SHELL': '/bin/bash', 09:20:11 INFO - 'SHLVL': '1', 09:20:11 INFO - 'TERM': 'linux', 09:20:11 INFO - 'TMOUT': '86400', 09:20:11 INFO - 'USER': 'cltbld', 09:20:11 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:11 INFO - '_': '/tools/buildbot/bin/python'} 09:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:20:11 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 09:20:11 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 09:20:11 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 09:20:11 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 09:20:11 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 09:20:11 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 09:20:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:20:13 INFO - Installing collected packages: mozsystemmonitor 09:20:13 INFO - Running setup.py install for mozsystemmonitor 09:20:14 INFO - Successfully installed mozsystemmonitor 09:20:14 INFO - Cleaning up... 09:20:14 INFO - Return code: 0 09:20:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:20:14 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')]} 09:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:14 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')]} 09:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:14 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:14 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 09:20:14 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 09:20:14 INFO - Using env: {'DISPLAY': ':0', 09:20:14 INFO - 'HOME': '/home/cltbld', 09:20:14 INFO - 'LANG': 'en_US.UTF-8', 09:20:14 INFO - 'LANGUAGE': 'en_US:en', 09:20:14 INFO - 'LOGNAME': 'cltbld', 09:20:14 INFO - 'MAIL': '/var/mail/cltbld', 09:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:14 INFO - 'MOZ_NO_REMOTE': '1', 09:20:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:14 INFO - 'NO_EM_RESTART': '1', 09:20:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:14 INFO - 'PWD': '/builds/slave/test', 09:20:14 INFO - 'SHELL': '/bin/bash', 09:20:14 INFO - 'SHLVL': '1', 09:20:14 INFO - 'TERM': 'linux', 09:20:14 INFO - 'TMOUT': '86400', 09:20:14 INFO - 'USER': 'cltbld', 09:20:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:14 INFO - '_': '/tools/buildbot/bin/python'} 09:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:20:14 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 09:20:14 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 09:20:14 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 09:20:14 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 09:20:14 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 09:20:14 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 09:20:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:16 INFO - warning: no files found matching '*.txt' 09:20:16 INFO - no previously-included directories found matching 'build' 09:20:16 INFO - no previously-included directories found matching 'dist' 09:20:16 INFO - no previously-included directories found matching '.git*' 09:20:16 INFO - Installing collected packages: jsonschema, functools32 09:20:16 INFO - Running setup.py install for functools32 09:20:16 INFO - warning: no files found matching '*.txt' 09:20:16 INFO - no previously-included directories found matching 'build' 09:20:16 INFO - no previously-included directories found matching 'dist' 09:20:16 INFO - no previously-included directories found matching '.git*' 09:20:16 INFO - Successfully installed jsonschema functools32 09:20:16 INFO - Cleaning up... 09:20:16 INFO - Return code: 0 09:20:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:20:16 INFO - Using env: {'DISPLAY': ':0', 09:20:16 INFO - 'HOME': '/home/cltbld', 09:20:16 INFO - 'LANG': 'en_US.UTF-8', 09:20:16 INFO - 'LANGUAGE': 'en_US:en', 09:20:16 INFO - 'LOGNAME': 'cltbld', 09:20:16 INFO - 'MAIL': '/var/mail/cltbld', 09:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:16 INFO - 'MOZ_NO_REMOTE': '1', 09:20:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:16 INFO - 'NO_EM_RESTART': '1', 09:20:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:16 INFO - 'PWD': '/builds/slave/test', 09:20:16 INFO - 'SHELL': '/bin/bash', 09:20:16 INFO - 'SHLVL': '1', 09:20:16 INFO - 'TERM': 'linux', 09:20:16 INFO - 'TMOUT': '86400', 09:20:16 INFO - 'USER': 'cltbld', 09:20:16 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:16 INFO - '_': '/tools/buildbot/bin/python'} 09:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:20:17 INFO - Cleaning up... 09:20:17 INFO - Return code: 0 09:20:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:17 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')]} 09:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:17 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')]} 09:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:17 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:17 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 09:20:17 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 09:20:17 INFO - Using env: {'DISPLAY': ':0', 09:20:17 INFO - 'HOME': '/home/cltbld', 09:20:17 INFO - 'LANG': 'en_US.UTF-8', 09:20:17 INFO - 'LANGUAGE': 'en_US:en', 09:20:17 INFO - 'LOGNAME': 'cltbld', 09:20:17 INFO - 'MAIL': '/var/mail/cltbld', 09:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:17 INFO - 'MOZ_NO_REMOTE': '1', 09:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:17 INFO - 'NO_EM_RESTART': '1', 09:20:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:17 INFO - 'PWD': '/builds/slave/test', 09:20:17 INFO - 'SHELL': '/bin/bash', 09:20:17 INFO - 'SHLVL': '1', 09:20:17 INFO - 'TERM': 'linux', 09:20:17 INFO - 'TMOUT': '86400', 09:20:17 INFO - 'USER': 'cltbld', 09:20:17 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:17 INFO - '_': '/tools/buildbot/bin/python'} 09:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:17 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 09:20:17 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 09:20:17 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 09:20:17 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 09:20:17 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 09:20:17 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 09:20:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 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 09:20:20 INFO - Downloading docopt-0.6.1.tar.gz 09:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:21 INFO - Running setup.py install for blobuploader 09:20:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:21 INFO - Running setup.py install for requests 09:20:21 INFO - Running setup.py install for docopt 09:20:21 INFO - Successfully installed blobuploader requests docopt 09:20:21 INFO - Cleaning up... 09:20:21 INFO - Return code: 0 09:20:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:21 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:21 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 09:20:21 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 09:20:21 INFO - Using env: {'DISPLAY': ':0', 09:20:21 INFO - 'HOME': '/home/cltbld', 09:20:21 INFO - 'LANG': 'en_US.UTF-8', 09:20:21 INFO - 'LANGUAGE': 'en_US:en', 09:20:21 INFO - 'LOGNAME': 'cltbld', 09:20:21 INFO - 'MAIL': '/var/mail/cltbld', 09:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:21 INFO - 'MOZ_NO_REMOTE': '1', 09:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:21 INFO - 'NO_EM_RESTART': '1', 09:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:21 INFO - 'PWD': '/builds/slave/test', 09:20:21 INFO - 'SHELL': '/bin/bash', 09:20:21 INFO - 'SHLVL': '1', 09:20:21 INFO - 'TERM': 'linux', 09:20:21 INFO - 'TMOUT': '86400', 09:20:21 INFO - 'USER': 'cltbld', 09:20:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:21 INFO - '_': '/tools/buildbot/bin/python'} 09:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:21 INFO - Running setup.py (path:/tmp/pip-V9hRp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:21 INFO - Running setup.py (path:/tmp/pip-B8ySPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:22 INFO - Running setup.py (path:/tmp/pip-2jR3RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:22 INFO - Running setup.py (path:/tmp/pip-JUMpT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:22 INFO - Running setup.py (path:/tmp/pip-d_KohC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:22 INFO - Running setup.py (path:/tmp/pip-b9A9sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:22 INFO - Running setup.py (path:/tmp/pip-vJZlbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:22 INFO - Running setup.py (path:/tmp/pip-gpP56Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:22 INFO - Running setup.py (path:/tmp/pip-tsQqqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:22 INFO - Running setup.py (path:/tmp/pip-j5a3gD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:22 INFO - Running setup.py (path:/tmp/pip-mCBG1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:22 INFO - Running setup.py (path:/tmp/pip-VjTrz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:22 INFO - Running setup.py (path:/tmp/pip-Ww5khF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:22 INFO - Running setup.py (path:/tmp/pip-G9jvbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:22 INFO - Running setup.py (path:/tmp/pip-_ivvXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:22 INFO - Running setup.py (path:/tmp/pip-SPhcLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:23 INFO - Running setup.py (path:/tmp/pip-8ETey7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:20:23 INFO - Running setup.py install for manifestparser 09:20:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Running setup.py install for mozcrash 09:20:23 INFO - Running setup.py install for mozdebug 09:20:23 INFO - Running setup.py install for mozdevice 09:20:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Running setup.py install for mozfile 09:20:23 INFO - Running setup.py install for mozhttpd 09:20:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Running setup.py install for mozinfo 09:20:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Running setup.py install for mozInstall 09:20:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:20:23 INFO - Running setup.py install for mozleak 09:20:24 INFO - Running setup.py install for mozlog 09:20:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Running setup.py install for moznetwork 09:20:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Running setup.py install for mozprocess 09:20:24 INFO - Running setup.py install for mozprofile 09:20:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Running setup.py install for mozrunner 09:20:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Running setup.py install for mozscreenshot 09:20:24 INFO - Running setup.py install for moztest 09:20:24 INFO - Running setup.py install for mozversion 09:20:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:20:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:20:24 INFO - Cleaning up... 09:20:24 INFO - Return code: 0 09:20:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:24 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:24 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 09:20:24 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 09:20:24 INFO - Using env: {'DISPLAY': ':0', 09:20:24 INFO - 'HOME': '/home/cltbld', 09:20:24 INFO - 'LANG': 'en_US.UTF-8', 09:20:24 INFO - 'LANGUAGE': 'en_US:en', 09:20:24 INFO - 'LOGNAME': 'cltbld', 09:20:24 INFO - 'MAIL': '/var/mail/cltbld', 09:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:24 INFO - 'MOZ_NO_REMOTE': '1', 09:20:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:24 INFO - 'NO_EM_RESTART': '1', 09:20:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:24 INFO - 'PWD': '/builds/slave/test', 09:20:24 INFO - 'SHELL': '/bin/bash', 09:20:24 INFO - 'SHLVL': '1', 09:20:24 INFO - 'TERM': 'linux', 09:20:24 INFO - 'TMOUT': '86400', 09:20:24 INFO - 'USER': 'cltbld', 09:20:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:24 INFO - '_': '/tools/buildbot/bin/python'} 09:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:25 INFO - Running setup.py (path:/tmp/pip-hnP2HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:25 INFO - Running setup.py (path:/tmp/pip-o9302E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:25 INFO - Running setup.py (path:/tmp/pip-BBNprL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:25 INFO - Running setup.py (path:/tmp/pip-BkG9Wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:25 INFO - Running setup.py (path:/tmp/pip-HTxUbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:25 INFO - Running setup.py (path:/tmp/pip-UpH9VP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:25 INFO - Running setup.py (path:/tmp/pip-y61bf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:25 INFO - Running setup.py (path:/tmp/pip-qWIx92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:25 INFO - Running setup.py (path:/tmp/pip-IMA06h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:25 INFO - Running setup.py (path:/tmp/pip-F54Nhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:25 INFO - Running setup.py (path:/tmp/pip-5j5kUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:25 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)) 09:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:25 INFO - Running setup.py (path:/tmp/pip-GnyykU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:26 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)) 09:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:26 INFO - Running setup.py (path:/tmp/pip-HrKqtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:26 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)) 09:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:26 INFO - Running setup.py (path:/tmp/pip-8idcC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:26 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)) 09:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:26 INFO - Running setup.py (path:/tmp/pip-KbPDEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:26 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)) 09:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:26 INFO - Running setup.py (path:/tmp/pip-r8QzqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:26 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)) 09:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:26 INFO - Running setup.py (path:/tmp/pip-a73yZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:26 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)) 09:20:26 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)) 09:20:26 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)) 09:20:26 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)) 09:20:26 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)) 09:20:26 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)) 09:20:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:28 INFO - Downloading blessings-1.6.tar.gz 09:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:20:28 INFO - Installing collected packages: blessings 09:20:28 INFO - Running setup.py install for blessings 09:20:28 INFO - Successfully installed blessings 09:20:28 INFO - Cleaning up... 09:20:28 INFO - Return code: 0 09:20:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:28 INFO - Reading from file tmpfile_stdout 09:20:28 INFO - Current package versions: 09:20:28 INFO - argparse == 1.2.1 09:20:28 INFO - blessings == 1.6 09:20:28 INFO - blobuploader == 1.2.4 09:20:28 INFO - docopt == 0.6.1 09:20:28 INFO - functools32 == 3.2.3-2 09:20:28 INFO - jsonschema == 2.5.1 09:20:28 INFO - manifestparser == 1.1 09:20:28 INFO - mozInstall == 1.12 09:20:28 INFO - mozcrash == 1.0 09:20:28 INFO - mozdebug == 0.1 09:20:28 INFO - mozdevice == 0.49 09:20:28 INFO - mozfile == 1.2 09:20:28 INFO - mozhttpd == 0.7 09:20:28 INFO - mozinfo == 0.9 09:20:28 INFO - mozleak == 0.1 09:20:28 INFO - mozlog == 3.4 09:20:28 INFO - moznetwork == 0.27 09:20:28 INFO - mozprocess == 0.25 09:20:28 INFO - mozprofile == 0.28 09:20:28 INFO - mozrunner == 6.13 09:20:28 INFO - mozscreenshot == 0.1 09:20:28 INFO - mozsystemmonitor == 0.3 09:20:28 INFO - moztest == 0.8 09:20:28 INFO - mozversion == 1.4 09:20:28 INFO - psutil == 3.1.1 09:20:28 INFO - requests == 1.2.3 09:20:28 INFO - wsgiref == 0.1.2 09:20:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:28 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:28 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 09:20:28 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 09:20:28 INFO - Using env: {'DISPLAY': ':0', 09:20:28 INFO - 'HOME': '/home/cltbld', 09:20:28 INFO - 'LANG': 'en_US.UTF-8', 09:20:28 INFO - 'LANGUAGE': 'en_US:en', 09:20:28 INFO - 'LOGNAME': 'cltbld', 09:20:28 INFO - 'MAIL': '/var/mail/cltbld', 09:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:28 INFO - 'MOZ_NO_REMOTE': '1', 09:20:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:28 INFO - 'NO_EM_RESTART': '1', 09:20:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:28 INFO - 'PWD': '/builds/slave/test', 09:20:28 INFO - 'SHELL': '/bin/bash', 09:20:28 INFO - 'SHLVL': '1', 09:20:28 INFO - 'TERM': 'linux', 09:20:28 INFO - 'TMOUT': '86400', 09:20:28 INFO - 'USER': 'cltbld', 09:20:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:28 INFO - '_': '/tools/buildbot/bin/python'} 09:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 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)) 09:20:29 INFO - Cleaning up... 09:20:29 INFO - Return code: 0 09:20:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:29 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 0x1ed41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, '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': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', '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 09:20:29 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 09:20:29 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 09:20:29 INFO - Using env: {'DISPLAY': ':0', 09:20:29 INFO - 'HOME': '/home/cltbld', 09:20:29 INFO - 'LANG': 'en_US.UTF-8', 09:20:29 INFO - 'LANGUAGE': 'en_US:en', 09:20:29 INFO - 'LOGNAME': 'cltbld', 09:20:29 INFO - 'MAIL': '/var/mail/cltbld', 09:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:29 INFO - 'MOZ_NO_REMOTE': '1', 09:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:29 INFO - 'NO_EM_RESTART': '1', 09:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:29 INFO - 'PWD': '/builds/slave/test', 09:20:29 INFO - 'SHELL': '/bin/bash', 09:20:29 INFO - 'SHLVL': '1', 09:20:29 INFO - 'TERM': 'linux', 09:20:29 INFO - 'TMOUT': '86400', 09:20:29 INFO - 'USER': 'cltbld', 09:20:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:29 INFO - '_': '/tools/buildbot/bin/python'} 09:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:20:29 INFO - Cleaning up... 09:20:29 INFO - Return code: 0 09:20:29 INFO - Running post-action listener: _resource_record_post_action 09:20:29 INFO - Running post-action listener: _start_resource_monitoring 09:20:29 INFO - Starting resource monitoring. 09:20:29 INFO - [mozharness: 2017-03-19 16:20:29.510834Z] Finished create-virtualenv step (success) 09:20:29 INFO - [mozharness: 2017-03-19 16:20:29.511361Z] Running install step. 09:20:29 INFO - Running pre-action listener: _resource_record_pre_action 09:20:29 INFO - Running main action method: install 09:20:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:29 INFO - Reading from file tmpfile_stdout 09:20:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:20:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:20:29 INFO - Reading from file tmpfile_stdout 09:20:29 INFO - Output received: 09:20:29 INFO - Usage: mozinstall [options] installer 09:20:29 INFO - Options: 09:20:29 INFO - -h, --help show this help message and exit 09:20:29 INFO - -d DEST, --destination=DEST 09:20:29 INFO - Directory to install application into. [default: 09:20:29 INFO - "/builds/slave/test"] 09:20:29 INFO - --app=APP Application being installed. [default: firefox] 09:20:29 INFO - mkdir: /builds/slave/test/build/application 09:20:29 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'] 09:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:20:46 INFO - Reading from file tmpfile_stdout 09:20:46 INFO - Output received: 09:20:46 INFO - /builds/slave/test/build/application/firefox/firefox 09:20:46 INFO - Running post-action listener: _resource_record_post_action 09:20:46 INFO - [mozharness: 2017-03-19 16:20:46.592517Z] Finished install step (success) 09:20:46 INFO - [mozharness: 2017-03-19 16:20:46.592793Z] Running run-tests step. 09:20:46 INFO - Running pre-action listener: _resource_record_pre_action 09:20:46 INFO - Running main action method: run_tests 09:20:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:20:46 INFO - Python 2.7.3 09:20:46 INFO - Return code: 0 09:20:46 INFO - grabbing minidump binary from tooltool 09:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:46 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 0x216f400>, '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 0x21703b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2170840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:20:46 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 09:20:46 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 09:20:46 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 09:20:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:20:46 INFO - Return code: 0 09:20:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:20:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:20:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:20:46 INFO - ENV: RUST_BACKTRACE is now 1 09:20:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:20:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:20:46 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/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 09:20:46 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/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 09:20:46 INFO - Using env: {'DISPLAY': ':0', 09:20:46 INFO - 'HOME': '/home/cltbld', 09:20:46 INFO - 'LANG': 'en_US.UTF-8', 09:20:46 INFO - 'LANGUAGE': 'en_US:en', 09:20:46 INFO - 'LOGNAME': 'cltbld', 09:20:46 INFO - 'MAIL': '/var/mail/cltbld', 09:20:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:20:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:46 INFO - 'MOZ_NO_REMOTE': '1', 09:20:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:46 INFO - 'NO_EM_RESTART': '1', 09:20:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:46 INFO - 'PWD': '/builds/slave/test', 09:20:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:20:46 INFO - 'RUST_BACKTRACE': '1', 09:20:46 INFO - 'SHELL': '/bin/bash', 09:20:46 INFO - 'SHLVL': '1', 09:20:46 INFO - 'TERM': 'linux', 09:20:46 INFO - 'TMOUT': '86400', 09:20:46 INFO - 'USER': 'cltbld', 09:20:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491', 09:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:46 INFO - '_': '/tools/buildbot/bin/python'} 09:20:46 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/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 09:20:46 INFO - mozversion application_buildid: 20170319152621 09:20:46 INFO - mozversion application_changeset: 1b9293be51637f841275541d8991314ca56561a5 09:20:46 INFO - mozversion application_display_name: Nightly 09:20:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:20:46 INFO - mozversion application_name: Firefox 09:20:46 INFO - mozversion application_remotingname: firefox 09:20:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:20:46 INFO - mozversion application_vendor: Mozilla 09:20:46 INFO - mozversion application_version: 55.0a1 09:20:46 INFO - mozversion platform_buildid: 20170319152621 09:20:46 INFO - mozversion platform_changeset: 1b9293be51637f841275541d8991314ca56561a5 09:20:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:20:46 INFO - mozversion platform_version: 55.0a1 09:20:46 INFO - using testdate: 1489940446 09:20:46 INFO - actual date: 1489940446 09:20:46 INFO - starting webserver on 'localhost:44188' 09:20:46 INFO - SUITE-START | Running 2 tests 09:20:46 INFO - TEST-START | dromaeo_css 09:20:46 INFO - operating with platform_type : linux_ 09:20:46 INFO - Initialising browser for dromaeo_css test... 09:20:46 INFO - TEST-INFO | started process 4883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7moEot/profile http://localhost:44188/getInfo.html) 09:20:50 INFO - PID 4883 | __metrics Screen width/height:1600/1200 09:20:50 INFO - PID 4883 | colorDepth:24 09:20:50 INFO - PID 4883 | Browser inner width/height: 1024/697 09:20:50 INFO - PID 4883 | __metrics 09:20:51 INFO - PID 4883 | 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" 09:20:51 INFO - PID 4883 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:20:52 INFO - TEST-INFO | 4883: exit 0 09:20:52 INFO - Browser initialized. 09:20:52 INFO - Running cycle 1/1 for dromaeo_css test... 09:20:52 INFO - TEST-INFO | started process 4981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7moEot/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:04 INFO - PID 4981 | RSS: Main: 144257024 09:21:04 INFO - PID 4981 | 09:24:06 INFO - PID 4981 | Cycle 1(1): loaded http://localhost:44188/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44188/tests/dromaeo/cssquery-ext.html) 09:24:07 INFO - PID 4981 | RSS: Main: 277929984 09:24:07 INFO - PID 4981 | 09:25:54 INFO - PID 4981 | ************************* 09:25:54 INFO - PID 4981 | A coding exception was thrown and uncaught in a Task. 09:25:54 INFO - PID 4981 | 09:25:54 ERROR - PID 4981 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:25:54 INFO - PID 4981 | Full stack: 09:25:54 INFO - PID 4981 | ************************* 09:27:10 INFO - PID 4981 | Cycle 1(1): loaded http://localhost:44188/tests/dromaeo/cssquery-ext.html (next: http://localhost:44188/tests/dromaeo/cssquery-jquery.html) 09:27:10 INFO - PID 4981 | RSS: Main: 227336192 09:27:10 INFO - PID 4981 | 09:30:12 INFO - PID 4981 | Cycle 1(1): loaded http://localhost:44188/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44188/tests/dromaeo/cssquery-mootools.html) 09:30:13 INFO - PID 4981 | RSS: Main: 176320512 09:30:13 INFO - PID 4981 | 09:33:15 INFO - PID 4981 | Cycle 1(1): loaded http://localhost:44188/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44188/tests/dromaeo/cssquery-prototype.html) 09:33:15 INFO - PID 4981 | RSS: Main: 236945408 09:33:15 INFO - PID 4981 | 09:36:18 INFO - PID 4981 | Cycle 1(1): loaded http://localhost:44188/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44188/tests/dromaeo/cssquery-yui.html) 09:36:19 INFO - PID 4981 | RSS: Main: 182784000 09:36:19 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | Cycle 1(1): loaded http://localhost:44188/tests/dromaeo/cssquery-yui.html (next: http://localhost:44188/tests/dromaeo/cssquery-dojo.html) 09:39:22 INFO - PID 4981 | RSS: Main: 278978560 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | __start_tp_report 09:39:22 INFO - PID 4981 | _x_x_mozilla_page_load 09:39:22 INFO - PID 4981 | _x_x_mozilla_page_load_details 09:39:22 INFO - PID 4981 | |i|pagename|runs| 09:39:22 INFO - PID 4981 | |0;dojo.html;3231.40219059474;3250.6099268087833;3273.6235332936703;3277.2298509850198;3304.6199687035996;5439.102548079562;5535.114381698929;5535.972320138399;5559.054960656688;5565.387807341194;5460.153676180188;5508.7521061552225;5515.503604675589;5582.302212223472;5623.184638227454;4991.326170966928;5037.798488060474;5040.1935690289565;5062.772175252123;5088.313077734496;4964.379452568429;5019.196928491442;5038.244263360507;5093.949060509403;5098.719570423626;4897.902041959176;5016.824411145592;5031.094403007493;5062.392512898439;5126.666766660173;4881.219004959189;4895.339129217546;4962.131626965267;5003.924941125886;5010.223415370623;4943.184374578191;4947.158982972904;4949.158643030693;4983.551480366748;5015.398152221756;5481.698506582136;5530.391656917735;5537.778488860441;5538.889222215533;5547.223388725582;5808.97095514524;5855.590108692393;5859.326177489601;5881.970590147064;5959.702014899238;6017.638941663514;6040.57715959883;6056.848578785522;6097.664628445434;6102.481289090432;5584.944150558483;5600.047991841438;5602.52378547828;5602.859928501779;5613.691246981373;5902;6011.248593925759;6159.753609855646;6161.599496032743;6175.228096487939;5627.212190293362;5769.480746732815;5892.911606325909;5923.911141332884;5930.199423077829;5805.796797112165;5823.854403639943;5828.154917536934;5828.59199856006;5831.154482599999;5855.2973643163095;5922.318933322636;5967;5967.015442452033;5976.462118409364;5935.554833387513;5952.345242023374;5953.315368732651;5974.641521508724;5994.3705910878725;4499.797509112098;4542.954570454253;4582.770861456914;4584.33527138563;4589.0821835633005;4045;4088.7342322749523;4090.877273681794;4102.487189101363;4117.3618089196225;5617.9438205618235;5642.435756424325;5643.633163844337;5649.12438572022;5697.743601537941;5794.565407594448;5987.401259873977;5994.9700251499335;5995.970020149872;6006.879862402815;5484.287042684426;5554;5576.470235327637;5576.776928922797;5579.497845193952;5009.924851127236;5035.672681275705;5049.040682270357;5055.595552355802;5057.544820966131;6101.519919784808;6152.3540028297275;6173.98129308677;6174.25908890936;6180.289266734293;6023.45788879003;6025;6029.51763858899;6040.818775436744;6058.151858739692;6015.789447369321;6229.3770622937345;6280.246370435577;6281.9948808190475;6287.811365659038;5779.133130030529;5801.071828507418;5911.704414779158;5922.615030023035;5926.111083337534;6003.589156548295;6013.398660133952;6014.969925150346;6017.458428741435;6023.638581684938;5815.4184581543195;5831.329397119385;5842.328132264844;5843.181954526284;5855.151003104611;5620.4660557247;5664.178694089416;5757.251557297524;5783.595148339744;5811.854703632274;5816.069428891357;5828.358880523224;5837.474627283565;5843.649381037151;5849.61977471463;5859.384764599656;5884.0585506318785;5889;5889.764409423575;5891.263592050994;5536.7785288588;5560.527355174693;5625.831225063254;5626.212330273846;5705.885882282248 09:39:22 INFO - PID 4981 | |1;ext.html;674.4806498995773;719.1514013464111;743.7359737293259;802.137701970382;812.6627449608413;30284;35389.46915796265;36016.63983360166;36167.45748813763;36227.81886090566;41657.791711041406;42247;42248.577514224846;42296.365554516706;42426.7878660607;7137.429005679559;8499.107593702665;8705.521196334199;8716.607752651114;8727.301815854747;7294.927050729504;9843.803123937552;10042;10059.949700251525;10080.495975201247;10263.332883362558;10300.22748293881;10333.741656458611;10334.173266138729;10336.276460647758;7206.711731530732;10236.692899213036;10254.7436314092;10273.02406271403;10291.382517048964;7253.746118885813;9790.314677972545;9986.101250887456;9989;10007.099361057468;35926.46110308347;36218.27563448743;40340.59659403398;40482.19035619301;40547.39178912289;3539.2490445923345;3547.875824346161;3566.1797786508987;3624.474451204587;3761.247750449921;25812.225633230846;25965;26241.868790656117;26517.602235966475;26818.329541761417;3968.523777146732;3992.5608183099835;4045.554988951213;4060.1270726793587;4083.775392353419;158415;158443;158609;158919;159092;42068.158636827095;42151;42188.578114218464;42195.57804421917;42203;44437.55562444399;44437.55562444399;44461.66615001555;44464;44505.33242001373;12661.113722039368;12677.809832852514;12678.619641410773;12803.359832008362;12807.103502754904;9129.224015958718;9134.954325228331;9994.950025249973;9998.100170984646;10005.79988400228;13610.183388996691;13706.177629342272;13820.170789752447;13828.792568111487;13862.098963567336;5575.856949325379;5786.537077033911;5804.65172089676;5807.390224026416;5872.647641141545;1113.8663360396804;1140.9087273018138;1149.6665966869514;1161.3206274329611;1208.800547909585;24333.513329733305;24570;25759.48481030369;26089.608655870175;26186.86906565455;1236.0111910471767;1238.3993763024926;1257.3336131850135;1273.5478904988781;1281.711614886643;13394.93302533481;13661.931690341484;13734.381952812148;13794.862051379358;13975.301234938212;310711;311265;312725.6001839796;313022;313193;53157.734211328694;53270.467295327326;53347.733261333444;53350.7332463343;53392.73303633535;1362.9182249065088;1416.221078406872;1422.7439060968918;1430.1562078373809;1520.7338715724704;24245.03019879185;24305.635415468427;25376.74623253818;25377.49245015126;25478.74521254838;39490.605093948696;43357.78321108374;43770.56229437792;43809.78095109504;43817.12365752731;342630;344556;347200;347232;347931;34563.481547776304;34931.47602786011;35122.47316290207;35410.46884296686;35906.28187436184;41843.16313673648;42771.7861410691;43133.352999705654;43554.34668479912;43563;36048;36199.457008144374;36431.635683642824;36457.08857278589;36606.267874642894;35687.82156089307;35712.10719732028;35909.1022724434;36014.639853601126;36066.45900311445;35937.28125437424;36433;36440.635593643725;36596.63403365932;36923.63076369202;30384.240393990327;32012.839935800173;32446.83776581102;32656.673433265365;32892.50661240131;24767.628485573092;25066.498670026864;25322.620160697974;25501.61747573751;25724.871375643 09:39:22 INFO - PID 4981 | |2;jquery.html;380.12381460733;415.88266512278676;431.3981995116811;443.1314623338257;446.0944283105296;16228.756568651464;19693.387384595393;21630.783692163073;21745.456363590878;21890.562188756234;20710.171593136292;20995.58008839826;21531.569368612618;21653.67519487209;21677.45806354842;5241.030409374278;5867.295924489067;6680.83297917551;6686.197656281253;6707.832304192385;6511.9348806512035;6663.600183988976;6665.000249962496;6665.533412661116;6673.966630166842;6382.553221274513;6568.277489476154;6584.736610535573;6595.274519802818;6603.471722262231;6521.119648847392;6527.347265273482;6527.902081468782;6533.738650453976;7302.780916572511;7414;7452.143003554552;7457.477976541644;7457.589832559207;7472.476926615139;23253.30240092783;23299.301020969397;23365.182218622438;23469.178578749834;23730;3141.748660107186;3179.8092114473206;3206.150370151912;3213.903582892517;3238.4170849247125;15336.539903802905;15637.765433518507;15774.290156942965;15804.446844360393;16178.75731864023;2128.553003869174;2166.6425039868404;2178.194068194762;2178.7821217878245;2190.893598732646;100585;109794.45102774515;109980.4500977498;110173;112915.80431326613;14970.550883473514;17747.201375938115;17918.91040544789;18363.35728249505;18614.627707445776;30081.63816246632;30240.69759302379;30260.69739302623;30340;30381.088567343017;7809.453338266267;8127.715529956541;8545.316374690134;8864.8670269946;8966.506842123616;6851.691673874688;6973.825654358681;7027.982795100861;7150.141982962078;7174.569525828467;9626.229901607854;9635.470049147365;9881.873238591976;9885.703428897144;9920.206383489447;3300.4389253826907;3533.2933413316923;3806.4480650306105;3942;4018.3369743991916;846.5428668519054;870.629982257538;882.2633101360436;882.748416701237;898.9011208767031;20832.687509687366;21821.781782182294;22177.55644887093;22207.66688499674;22225.66646001245;864.8270345930839;873.2664561768145;875.0767939823547;886.1360173830464;889.7908991387019;11356.602518911799;11927.403629818475;12015.579454719043;12118.636440906786;12131.818022729665;653911;678596;682184;687296;697882;111781;116882.41558792321;117928.41035794938;119539.40230298793;120702;1226.399064458427;1263.6398626391435;1289.3295486347236;1296.598054603076;1300.9349532523336;19433.902830485757;19769.505762356057;20574.074166662238;20592.79407205909;20706.378808635764;34211.48682769711;44752.77623611861;50473.99052018866;50571.24143137782;51417.916789553674;54686.72656636692;55308;55774.721126394106;55789.72105139448;56564.717176413855;11437.693960372231;11985.64043078709;12382.504699811907;12709.17390369623;12829.743405131658;14279.286035698382;14413.351399186851;14476.348564314841;14877.776833347292;15026;11843.58547450835;11900.880991189977;12648.494060237856;12710.936445317715;12965.740685186054;11396.886031139915;12138.939305303416;12261.203021803554;12480.126391152533;12530.37348132608;11601.477933492844;12974.935125324313;13229;13261.933690331487;13465.730685386434;12324.260544367367;12331.876681233432;12511.124221304786;12583.559575414816;12926.870731292567;12407;12594.874051259736;12786.68033299175;13123.737525249251;13141.868581314448 09:39:22 INFO - PID 4981 | |3;mootools.html;1505.6612262241003;1509.554681368996;1516.476815498653;1525.9999999999993;1608.871290296776;10319.174466042718;10747.570097196112;10769.676909692702;10774.353538787678;10776.73058173545;10342;10402.271840971134;10717.785644287129;10731.517081731321;10735.83896241555;2180.7819218078225;2217.2018073493527;2223.321886824526;2226.487907781211;2229.442639340165;2140.475583482041;2165.0906619219886;2173.076442511934;2184.3665337052234;2187.354730354541;2098.244631932511;2102.0645812613393;2116.460302622837;2117.301290574107;2129.4356995396156;2102.358780571925;2111.4510227340925;2146.967795483069;2151.0535364439597;2153.203314773536;2039.8062184092487;2160.4274867160216;2160.5030842906062;2164.3615133535645;2165.447810808234;10944.343339399662;11028.44857757117;11049.613263535817;11062.446877656166;11065.834012489819;5166.870828229286;5352;5386.326709161355;5391.245225668409;5392.757325920343;3158.873645054195;3319.5684561006915;3383.8646454141804;3391.559097317333;3408.2672225471642;1115.972100697481;1155.7110722319421;1160.2980196980789;1222.2422098299023;1225.307701148857;13505.12216705911;13866.029377943549;14922.925385373112;15004.474843380429;15007.099574025484;4197.874063778091;4325.653947684241;4388.824447022147;4436.157130145262;4446.133004064176;11537;11537.538498460135;11552.711182220512;11555.711107222218;11557.595484158182;4518.299663552155;4689.812407503661;5105.770240339194;5261.158214685632;5375.091842395971;4777.187878060738;4859.416869975626;4869.123557759637;4871.902561948741;4873.634477414208;3499.9125021874656;3522.5244591980268;3561.4301711726516;3569.196930690574;3570.8571657133425;895.9417637853519;912.5756523216736;913.0869130869131;918.8851393575803;927.939683920543;972.3873959405529;977.8142152990908;1000.1948431512557;1004.9748756281006;1016.598443614768;6515.4787616990525;6519.250286217051;6581.901271480931;6684.699188536528;6687.130673012477;966.4877614864133;978.2858513285341;991.375433476905;1006.6828948881079;1022.0239671114068;3706.369917114097;4487.6634252431195;4717.386739723883;4722;4834.564889159992;13032.80450793239;13196.472141114247;13265.270410127536;13283.667908302177;13410.530631428048;12821;12954.028447866447;13185.274809885548;13793.034487585772;13860.861391386159;10027.197824174049;10125.240606954509;10183.08352248316;10291.639792607222;10301.896981030244;6448.5163612730175;6530.281669016499;6559;6561.901263736892;6761.121054263111;11396.373199474108;11548.422578870855;12294.139410241198;12342.382880855741;12371;11486.65540033767;11633;11655.650330490098;11656;11668.88331116678;8372.497650141011;8654.78363040929;8663.26362259215;8667.263282621048;8694.130586941255;9330.95334523323;9432.434053956784;9584.37701549397;9592.328537002342;9600.231981441608;8428.915710842813;8446.788830279293;8540.572971351532;8788.209061184523;8820.91179088201;7985.321247694011;8025.518468891885;8028.036635603765;8572.185642363966;8644.481331120152;7182.60495672743;7196.676149573386;7263.636818158965;7306.634668266672;7345.926540734525;5684.971575142098;5703.543716502752;5712.914306285326;5789.3921138279875;5964.7912323068485;5702.572307076903;5751.597388182954;5752.079667253387;5867.911981320198;5925.489000305067 09:39:22 INFO - PID 4981 | |4;prototype.html;393.64965180988906;396.540013584242;403.26203048421394;411.42400639105267;412.85550057479884;5716.914246286309;5739.282589676291;5760.36635970043;5787.421257874215;5814.854628634286;5489.286392768934;5669.09294512878;5682.318121825377;5685.1756495308155;5699.68651724155;4495.842645507408;4507.188706032921;4518.502964673883;4521.796519156645;4533.705309154894;4497.123061003105;4516.503184649686;4523;4537.2740361542155;4550.314245461171;4424.84513042042;4479.686421950465;4483.439570053743;4490.946156613501;4512.16524942885;4462.866114016585;4481.512760417967;4502.122086193193;4508.66185036122;4515.977420112911;4040.333344998072;4068.776217308047;4070.4708387909677;4089.345704687236;4090.404742150534;5425.186222066682;5508.504234618904;5568.638038527482;5570.220169176318;5580.720963951786;5250.8687282817855;5254.343207099113;5265.289185959885;5281.181416880389;5303.124984377573;6039.667818269993;6042.274927008743;6049.364816694272;6054.96972515139;6083.148359229712;4539.704919180276;4541.977290113561;4551.726896386194;4633.189191891405;4841.489919808111;5988.706211583655;6182.907256391158;6203.193584833988;6298.49612031041;6356.014817703217;5355.785768569213;5425.348958124971;5493.505584497414;5498.422665620053;5506;5530.557555395559;5561.972190139023;5566.749496272678;5583.832485025456;5620.615919582732;5630.324361076697;5667.574931880125;5733.627314224562;5734.655920644775;5743.712814359265;6041.909371359433;6064.0297552392285;6070.696465176724;6090.786822461193;6092.573519853657;1887.5469887226966;1944.2417457191707;1952.384998725397;1955.3742802303127;1965.744453221072;1559.7270477666364;1576.393088660874;1591.6020994751311;1604.5507257967554;1619.1256721370328;5815.476607105381;5850.5904586678525;5874.970625146932;5878.29460464747;5894.233749612523;6213.502919766408;6218.31598524162;6232.594881332699;6241.313455519889;6253.6560489173535;5643.548516118701;5755.165501002331;5808.477237048686;5830.475257226785;5883.294004719461;3271.6237632672073;3273.4435146025235;3286.14560214346;3302.240484688535;3304.0253125328086;6302.676437948082;6427.143713771699;6480.157579514633;6485.794829757905;6517.055027021055;6225.564210505312;6261.279952805395;6289.371062893674;6322.146510221061;6327.462165715873;6405.871882562323;6429.228492580826;6430.935690643034;6460.3216662250725;6468.482521398371;6017.428344307284;6189.35011823752;6202.503799696104;6210.130581718653;6219.222597175353;5846.736896839568;6034.456898879122;6035.6076855004285;6154.3845615438095;6158.3225845157895;5945.940540594712;6317.778877739257;6325.652089135142;6363.427291543783;6447.516436267205;4858.684185527929;5422.783088676409;5428.267183930119;5453.7818487260065;5464.453554644504;6193.504519638508;6214.347493513297;6231.781887633911;6243.937560624336;6249.156363890998;5963.761449541969;5980.910286345796;5995.130706047686;6021.969890150521;6045.848853778515;5787.276590426197;6010.038393856962;6019.699015049143;6028.246469191351;6036.154938308729;6031.215941927521;6041.9395806041375;6055.485283750931;6061.212042434456;6063.454289114001;5878.500327472213;6058.939410605837;6108.938910611015;6127.785527506516;6139.877202456015;5895.823125306248;5905.734241959036;5909.674967876808;5914.556408269311;5918.201042859159 09:39:22 INFO - PID 4981 | |5;yui.html;754.6943487887407;765.7664412354229;806.661202295036;814.2061490047201;826.5247482697448;15682.451114211004;16459.423920162797;16470.17649117542;16502.257398417085;16504.422345217903;12965.675858103552;12979.22124672518;12982.740345193091;13204.405801738922;13582.524611638595;831.5094094484253;852.5438890193748;857.8927634045745;859.9011113721908;860.849351363512;830.8795224692416;847.220557087481;848.8896443462341;849.2102344819316;849.5030407211758;884.8274586455643;893.1648908270788;893.6023469556018;894.0790985285166;894.7986702991807;878.9868151977694;885.583775625455;885.9025507194235;887.1084560017174;887.4941283468426;642.8425035866243;646.6217262901162;650.0898741761524;650.3171669746747;650.6258901131821;16027;16416.507504774876;17248.223829927683;17308.82691173085;17994.91002544979;15949.681006379924;16359;16455.177241137895;16633.25150368224;16771.83228167727;12238.632841014783;12526.74946501065;12662.493500259978;12833.165844220095;13285.202887826761;3600;3652.2330310634798;3722.2927643747603;3735.869244576454;3813.3326667833016;87141;87643;87690.56154719186;87827.56086219593;88699;11780.587679431179;11935.820962685566;11944.641660750018;12096;12192.085593580518;25443.87278063598;25536.744632553808;25623.615645764956;25805.3548661285;25836.478616242082;8205.876911846328;8222.876656850152;8296.294814940677;8322.292605128632;8332.458390204618;3893.2992061429213;3896.688264938798;3913.25648126855;3948.9210215796097;3958.1687845552183;3417;3457.3949558827103;3502;3524.0308915048568;3541.7520773545602;568.9644846379549;570.7688386203595;573.6214098694842;574.8505388598994;579.6000759475949;615.30163264694;628.5380245519631;635.581611966472;647.9190101237346;670.7585269303047;9531.37966545675;9579.13787759105;9779.951100244452;9789.412635241908;10238.46772863361;531.9069162896477;547.2284079447959;553.5626854784675;584.7251791657914;665.890128128863;4515.774211289422;4674.392328997276;4780.259059845729;4794.065157294364;4865.489123642037;71346;77916.22083779203;78126.60936695394;79246.6037669808;81969.18030819735;67482.3251767476;68715;69632.3036769636;69965;72070.27929720741;50779.36750752307;58849.99999999914;59088.70455647694;60118;62435;8646.481211127353;9053.275737940818;9078.54607269647;9115.262846606089;9144.131307525775;40546;41038;41539.58460415357;41839.37240941449;42628.54000549953;50286.831682179705;51156.48843511517;51766.74116629393;52548;53388.46611533835;10958.178136639943;11067.557297708323;11241.437928103398;11256.437178141225;11269.71825704364;11832.290062596272;11973.76052478928;12193.329366884907;12194.085443591588;12322.260664360167;11602.6519204424;12086.15396922207;12342.227192842636;12634.115611907446;12636.810447843105;11573.652790416301;11583.536658533565;11852;11947.701307467036;12492.500299987898;11200.831987520356;11759.176857619885;12055.276683399024;12215.633530994084;12224.266544007389;10021.39871607706;10421.9478902605;10618.309809862334;10745.462726863781;10794.08250298702;8631.525266110424;9081.364304498622;9859.457729824642;10118.848217276895;10139.64511242103 09:39:22 INFO - PID 4981 | __end_tp_report 09:39:22 INFO - PID 4981 | __start_cc_report 09:39:22 INFO - PID 4981 | _x_x_mozilla_cycle_collect,1411 09:39:22 INFO - PID 4981 | __end_cc_report 09:39:22 INFO - PID 4981 | __startTimestamp1489941562302__endTimestamp 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | ------- Summary: start ------- 09:39:22 INFO - PID 4981 | Number of tests: 6 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | [#0] dojo.html Cycles:165 Average:5518.20 Median:5705.89 stddev:632.01 (11.1%) stddev-sans-first:607.96 09:39:22 INFO - PID 4981 | Values: 3231.4 3250.6 3273.6 3277.2 3304.6 5439.1 5535.1 5536.0 5559.1 5565.4 5460.2 5508.8 5515.5 5582.3 5623.2 4991.3 5037.8 5040.2 5062.8 5088.3 4964.4 5019.2 5038.2 5093.9 5098.7 4897.9 5016.8 5031.1 5062.4 5126.7 4881.2 4895.3 4962.1 5003.9 5010.2 4943.2 4947.2 4949.2 4983.6 5015.4 5481.7 5530.4 5537.8 5538.9 5547.2 5809.0 5855.6 5859.3 5882.0 5959.7 6017.6 6040.6 6056.8 6097.7 6102.5 5584.9 5600.0 5602.5 5602.9 5613.7 5902.0 6011.2 6159.8 6161.6 6175.2 5627.2 5769.5 5892.9 5923.9 5930.2 5805.8 5823.9 5828.2 5828.6 5831.2 5855.3 5922.3 5967.0 5967.0 5976.5 5935.6 5952.3 5953.3 5974.6 5994.4 4499.8 4543.0 4582.8 4584.3 4589.1 4045.0 4088.7 4090.9 4102.5 4117.4 5617.9 5642.4 5643.6 5649.1 5697.7 5794.6 5987.4 5995.0 5996.0 6006.9 5484.3 5554.0 5576.5 5576.8 5579.5 5009.9 5035.7 5049.0 5055.6 5057.5 6101.5 6152.4 6174.0 6174.3 6180.3 6023.5 6025.0 6029.5 6040.8 6058.2 6015.8 6229.4 6280.2 6282.0 6287.8 5779.1 5801.1 5911.7 5922.6 5926.1 6003.6 6013.4 6015.0 6017.5 6023.6 5815.4 5831.3 5842.3 5843.2 5855.2 5620.5 5664.2 5757.3 5783.6 5811.9 5816.1 5828.4 5837.5 5843.6 5849.6 5859.4 5884.1 5889.0 5889.8 5891.3 5536.8 5560.5 5625.8 5626.2 5705.9 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | [#1] ext.html Cycles:180 Average:43087.02 Median:19479.17 stddev:74781.81 (383.9%) stddev-sans-first:74923.79 09:39:22 INFO - PID 4981 | Values: 674.5 719.2 743.7 802.1 812.7 30284.0 35389.5 36016.6 36167.5 36227.8 41657.8 42247.0 42248.6 42296.4 42426.8 7137.4 8499.1 8705.5 8716.6 8727.3 7294.9 9843.8 10042.0 10059.9 10080.5 10263.3 10300.2 10333.7 10334.2 10336.3 7206.7 10236.7 10254.7 10273.0 10291.4 7253.7 9790.3 9986.1 9989.0 10007.1 35926.5 36218.3 40340.6 40482.2 40547.4 3539.2 3547.9 3566.2 3624.5 3761.2 25812.2 25965.0 26241.9 26517.6 26818.3 3968.5 3992.6 4045.6 4060.1 4083.8 158415.0 158443.0 158609.0 158919.0 159092.0 42068.2 42151.0 42188.6 42195.6 42203.0 44437.6 44437.6 44461.7 44464.0 44505.3 12661.1 12677.8 12678.6 12803.4 12807.1 9129.2 9135.0 9995.0 9998.1 10005.8 13610.2 13706.2 13820.2 13828.8 13862.1 5575.9 5786.5 5804.7 5807.4 5872.6 1113.9 1140.9 1149.7 1161.3 1208.8 24333.5 24570.0 25759.5 26089.6 26186.9 1236.0 1238.4 1257.3 1273.5 1281.7 13394.9 13661.9 13734.4 13794.9 13975.3 310711.0 311265.0 312725.6 313022.0 313193.0 53157.7 53270.5 53347.7 53350.7 53392.7 1362.9 1416.2 1422.7 1430.2 1520.7 24245.0 24305.6 25376.7 25377.5 25478.7 39490.6 43357.8 43770.6 43809.8 43817.1 342630.0 344556.0 347200.0 347232.0 347931.0 34563.5 34931.5 35122.5 35410.5 35906.3 41843.2 42771.8 43133.4 43554.3 43563.0 36048.0 36199.5 36431.6 36457.1 36606.3 35687.8 35712.1 35909.1 36014.6 36066.5 35937.3 36433.0 36440.6 36596.6 36923.6 30384.2 32012.8 32446.8 32656.7 32892.5 24767.6 25066.5 25322.6 25501.6 25724.9 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | [#2] jquery.html Cycles:180 Average:37542.07 Median:23276.30 stddev:111901.04 (480.8%) stddev-sans-first:112180.16 09:39:22 INFO - PID 4981 | Values: 380.1 415.9 431.4 443.1 446.1 16228.8 19693.4 21630.8 21745.5 21890.6 20710.2 20995.6 21531.6 21653.7 21677.5 5241.0 5867.3 6680.8 6686.2 6707.8 6511.9 6663.6 6665.0 6665.5 6674.0 6382.6 6568.3 6584.7 6595.3 6603.5 6521.1 6527.3 6527.9 6533.7 7302.8 7414.0 7452.1 7457.5 7457.6 7472.5 23253.3 23299.3 23365.2 23469.2 23730.0 3141.7 3179.8 3206.2 3213.9 3238.4 15336.5 15637.8 15774.3 15804.4 16178.8 2128.6 2166.6 2178.2 2178.8 2190.9 100585.0 109794.5 109980.5 110173.0 112915.8 14970.6 17747.2 17918.9 18363.4 18614.6 30081.6 30240.7 30260.7 30340.0 30381.1 7809.5 8127.7 8545.3 8864.9 8966.5 6851.7 6973.8 7028.0 7150.1 7174.6 9626.2 9635.5 9881.9 9885.7 9920.2 3300.4 3533.3 3806.4 3942.0 4018.3 846.5 870.6 882.3 882.7 898.9 20832.7 21821.8 22177.6 22207.7 22225.7 864.8 873.3 875.1 886.1 889.8 11356.6 11927.4 12015.6 12118.6 12131.8 653911.0 678596.0 682184.0 687296.0 697882.0 111781.0 116882.4 117928.4 119539.4 120702.0 1226.4 1263.6 1289.3 1296.6 1300.9 19433.9 19769.5 20574.1 20592.8 20706.4 34211.5 44752.8 50474.0 50571.2 51417.9 54686.7 55308.0 55774.7 55789.7 56564.7 11437.7 11985.6 12382.5 12709.2 12829.7 14279.3 14413.4 14476.3 14877.8 15026.0 11843.6 11900.9 12648.5 12710.9 12965.7 11396.9 12138.9 12261.2 12480.1 12530.4 11601.5 12974.9 13229.0 13261.9 13465.7 12324.3 12331.9 12511.1 12583.6 12926.9 12407.0 12594.9 12786.7 13123.7 13141.9 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | [#3] mootools.html Cycles:180 Average:6470.05 Median:3239.22 stddev:4102.08 (126.6%) stddev-sans-first:4096.63 09:39:22 INFO - PID 4981 | Values: 1505.7 1509.6 1516.5 1526.0 1608.9 10319.2 10747.6 10769.7 10774.4 10776.7 10342.0 10402.3 10717.8 10731.5 10735.8 2180.8 2217.2 2223.3 2226.5 2229.4 2140.5 2165.1 2173.1 2184.4 2187.4 2098.2 2102.1 2116.5 2117.3 2129.4 2102.4 2111.5 2147.0 2151.1 2153.2 2039.8 2160.4 2160.5 2164.4 2165.4 10944.3 11028.4 11049.6 11062.4 11065.8 5166.9 5352.0 5386.3 5391.2 5392.8 3158.9 3319.6 3383.9 3391.6 3408.3 1116.0 1155.7 1160.3 1222.2 1225.3 13505.1 13866.0 14922.9 15004.5 15007.1 4197.9 4325.7 4388.8 4436.2 4446.1 11537.0 11537.5 11552.7 11555.7 11557.6 4518.3 4689.8 5105.8 5261.2 5375.1 4777.2 4859.4 4869.1 4871.9 4873.6 3499.9 3522.5 3561.4 3569.2 3570.9 895.9 912.6 913.1 918.9 927.9 972.4 977.8 1000.2 1005.0 1016.6 6515.5 6519.3 6581.9 6684.7 6687.1 966.5 978.3 991.4 1006.7 1022.0 3706.4 4487.7 4717.4 4722.0 4834.6 13032.8 13196.5 13265.3 13283.7 13410.5 12821.0 12954.0 13185.3 13793.0 13860.9 10027.2 10125.2 10183.1 10291.6 10301.9 6448.5 6530.3 6559.0 6561.9 6761.1 11396.4 11548.4 12294.1 12342.4 12371.0 11486.7 11633.0 11655.7 11656.0 11668.9 8372.5 8654.8 8663.3 8667.3 8694.1 9331.0 9432.4 9584.4 9592.3 9600.2 8428.9 8446.8 8540.6 8788.2 8820.9 7985.3 8025.5 8028.0 8572.2 8644.5 7182.6 7196.7 7263.6 7306.6 7345.9 5685.0 5703.5 5712.9 5789.4 5964.8 5702.6 5751.6 5752.1 5867.9 5925.5 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | [#4] prototype.html Cycles:180 Average:5230.35 Median:5787.35 stddev:1406.43 (24.3%) stddev-sans-first:1362.72 09:39:22 INFO - PID 4981 | Values: 393.6 396.5 403.3 411.4 412.9 5716.9 5739.3 5760.4 5787.4 5814.9 5489.3 5669.1 5682.3 5685.2 5699.7 4495.8 4507.2 4518.5 4521.8 4533.7 4497.1 4516.5 4523.0 4537.3 4550.3 4424.8 4479.7 4483.4 4490.9 4512.2 4462.9 4481.5 4502.1 4508.7 4516.0 4040.3 4068.8 4070.5 4089.3 4090.4 5425.2 5508.5 5568.6 5570.2 5580.7 5250.9 5254.3 5265.3 5281.2 5303.1 6039.7 6042.3 6049.4 6055.0 6083.1 4539.7 4542.0 4551.7 4633.2 4841.5 5988.7 6182.9 6203.2 6298.5 6356.0 5355.8 5425.3 5493.5 5498.4 5506.0 5530.6 5562.0 5566.7 5583.8 5620.6 5630.3 5667.6 5733.6 5734.7 5743.7 6041.9 6064.0 6070.7 6090.8 6092.6 1887.5 1944.2 1952.4 1955.4 1965.7 1559.7 1576.4 1591.6 1604.6 1619.1 5815.5 5850.6 5875.0 5878.3 5894.2 6213.5 6218.3 6232.6 6241.3 6253.7 5643.5 5755.2 5808.5 5830.5 5883.3 3271.6 3273.4 3286.1 3302.2 3304.0 6302.7 6427.1 6480.2 6485.8 6517.1 6225.6 6261.3 6289.4 6322.1 6327.5 6405.9 6429.2 6430.9 6460.3 6468.5 6017.4 6189.4 6202.5 6210.1 6219.2 5846.7 6034.5 6035.6 6154.4 6158.3 5945.9 6317.8 6325.7 6363.4 6447.5 4858.7 5422.8 5428.3 5453.8 5464.5 6193.5 6214.3 6231.8 6243.9 6249.2 5963.8 5980.9 5995.1 6022.0 6045.8 5787.3 6010.0 6019.7 6028.2 6036.2 6031.2 6041.9 6055.5 6061.2 6063.5 5878.5 6058.9 6108.9 6127.8 6139.9 5895.8 5905.7 5909.7 5914.6 5918.2 09:39:22 INFO - PID 4981 | 09:39:22 INFO - PID 4981 | [#5] yui.html Cycles:180 Average:17470.78 Median:4787.16 stddev:22806.53 (476.4%) stddev-sans-first:22835.97 09:39:22 INFO - PID 4981 | Values: 754.7 765.8 806.7 814.2 826.5 15682.5 16459.4 16470.2 16502.3 16504.4 12965.7 12979.2 12982.7 13204.4 13582.5 831.5 852.5 857.9 859.9 860.8 830.9 847.2 848.9 849.2 849.5 884.8 893.2 893.6 894.1 894.8 879.0 885.6 885.9 887.1 887.5 642.8 646.6 650.1 650.3 650.6 16027.0 16416.5 17248.2 17308.8 17994.9 15949.7 16359.0 16455.2 16633.3 16771.8 12238.6 12526.7 12662.5 12833.2 13285.2 3600.0 3652.2 3722.3 3735.9 3813.3 87141.0 87643.0 87690.6 87827.6 88699.0 11780.6 11935.8 11944.6 12096.0 12192.1 25443.9 25536.7 25623.6 25805.4 25836.5 8205.9 8222.9 8296.3 8322.3 8332.5 3893.3 3896.7 3913.3 3948.9 3958.2 3417.0 3457.4 3502.0 3524.0 3541.8 569.0 570.8 573.6 574.9 579.6 615.3 628.5 635.6 647.9 670.8 9531.4 9579.1 9780.0 9789.4 10238.5 531.9 547.2 553.6 584.7 665.9 4515.8 4674.4 4780.3 4794.1 4865.5 71346.0 77916.2 78126.6 79246.6 81969.2 67482.3 68715.0 69632.3 69965.0 72070.3 50779.4 58850.0 59088.7 60118.0 62435.0 8646.5 9053.3 9078.5 9115.3 9144.1 40546.0 41038.0 41539.6 41839.4 42628.5 50286.8 51156.5 51766.7 52548.0 53388.5 10958.2 11067.6 11241.4 11256.4 11269.7 11832.3 11973.8 12193.3 12194.1 12322.3 11602.7 12086.2 12342.2 12634.1 12636.8 11573.7 11583.5 11852.0 11947.7 12492.5 11200.8 11759.2 12055.3 12215.6 12224.3 10021.4 10421.9 10618.3 10745.5 10794.1 8631.5 9081.4 9859.5 10118.8 10139.6 09:39:22 INFO - PID 4981 | -------- Summary: end -------- 09:39:22 INFO - PID 4981 | 09:39:23 INFO - TEST-INFO | 4981: exit 0 09:39:23 INFO - TEST-OK | dromaeo_css | took 1116264ms 09:39:23 INFO - TEST-START | kraken 09:39:23 INFO - operating with platform_type : linux_ 09:39:23 INFO - Initialising browser for kraken test... 09:39:23 INFO - TEST-INFO | started process 5308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoDlEDO/profile http://localhost:44188/getInfo.html) 09:39:26 INFO - PID 5308 | __metrics Screen width/height:1600/1200 09:39:26 INFO - PID 5308 | colorDepth:24 09:39:26 INFO - PID 5308 | Browser inner width/height: 1024/697 09:39:26 INFO - PID 5308 | __metrics 09:39:27 INFO - PID 5308 | 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" 09:39:27 INFO - PID 5308 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:28 INFO - TEST-INFO | 5308: exit 0 09:39:28 INFO - Browser initialized. 09:39:28 INFO - Running cycle 1/1 for kraken test... 09:39:28 INFO - TEST-INFO | started process 5403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoDlEDO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:40 INFO - PID 5403 | RSS: Main: 144879616 09:39:40 INFO - PID 5403 | 09:39:43 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44188/tests/kraken/driver.html?testName=audio-beat-detection) 09:39:43 INFO - PID 5403 | RSS: Main: 199221248 09:39:43 INFO - PID 5403 | 09:39:46 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44188/tests/kraken/driver.html?testName=audio-dft) 09:39:47 INFO - PID 5403 | RSS: Main: 179478528 09:39:47 INFO - PID 5403 | 09:39:50 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44188/tests/kraken/driver.html?testName=audio-fft) 09:39:50 INFO - PID 5403 | RSS: Main: 260067328 09:39:50 INFO - PID 5403 | 09:39:52 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44188/tests/kraken/driver.html?testName=audio-oscillator) 09:39:53 INFO - PID 5403 | RSS: Main: 171339776 09:39:53 INFO - PID 5403 | 09:39:55 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44188/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:39:56 INFO - PID 5403 | RSS: Main: 175960064 09:39:56 INFO - PID 5403 | 09:39:59 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44188/tests/kraken/driver.html?testName=imaging-darkroom) 09:39:59 INFO - PID 5403 | RSS: Main: 214515712 09:39:59 INFO - PID 5403 | 09:40:03 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44188/tests/kraken/driver.html?testName=imaging-desaturate) 09:40:03 INFO - PID 5403 | RSS: Main: 226598912 09:40:03 INFO - PID 5403 | 09:40:06 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44188/tests/kraken/driver.html?testName=json-parse-financial) 09:40:07 INFO - PID 5403 | RSS: Main: 237625344 09:40:07 INFO - PID 5403 | 09:40:08 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44188/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:40:09 INFO - PID 5403 | RSS: Main: 177999872 09:40:09 INFO - PID 5403 | 09:40:11 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:40:11 INFO - PID 5403 | RSS: Main: 185896960 09:40:11 INFO - PID 5403 | 09:40:13 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:14 INFO - PID 5403 | RSS: Main: 194101248 09:40:14 INFO - PID 5403 | 09:40:16 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:40:17 INFO - PID 5403 | RSS: Main: 203223040 09:40:17 INFO - PID 5403 | 09:40:20 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:21 INFO - PID 5403 | RSS: Main: 199397376 09:40:21 INFO - PID 5403 | 09:40:22 INFO - PID 5403 | Cycle 1(1): loaded http://localhost:44188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44188/tests/kraken/driver.html?testName=ai-astar) 09:40:23 INFO - PID 5403 | RSS: Main: 267149312 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | __start_tp_report 09:40:23 INFO - PID 5403 | _x_x_mozilla_page_load 09:40:23 INFO - PID 5403 | _x_x_mozilla_page_load_details 09:40:23 INFO - PID 5403 | |i|pagename|runs| 09:40:23 INFO - PID 5403 | |0;ai-astar;111;115;113;112;113;114;115;114;114;112 09:40:23 INFO - PID 5403 | |1;audio-beat-detection;142;119;119;159;118;117;119;122;142;118 09:40:23 INFO - PID 5403 | |2;audio-dft;160;161;163;160;162;161;163;162;163;162 09:40:23 INFO - PID 5403 | |3;audio-fft;78;78;74;116;74;74;77;74;91;74 09:40:23 INFO - PID 5403 | |4;audio-oscillator;84;83;101;84;83;83;97;83;83;106 09:40:23 INFO - PID 5403 | |5;imaging-gaussian-blur;113;113;113;112;113;112;113;112;113;113 09:40:23 INFO - PID 5403 | |6;imaging-darkroom;147;147;146;147;147;147;146;147;148;146 09:40:23 INFO - PID 5403 | |7;imaging-desaturate;94;94;94;95;94;94;94;94;94;94 09:40:23 INFO - PID 5403 | |8;json-parse-financial;55;55;55;54;55;55;56;56;56;56 09:40:23 INFO - PID 5403 | |9;json-stringify-tinderbox;55;55;54;54;54;54;54;54;53;54 09:40:23 INFO - PID 5403 | |10;stanford-crypto-aes;81;81;80;80;78;86;78;78;78;79 09:40:23 INFO - PID 5403 | |11;stanford-crypto-ccm;137;132;124;128;128;122;128;127;167;126 09:40:23 INFO - PID 5403 | |12;stanford-crypto-pbkdf2;188;178;181;179;179;190;179;180;180;179 09:40:23 INFO - PID 5403 | |13;stanford-crypto-sha256-iterative;66;64;64;65;66;67;65;64;66;68 09:40:23 INFO - PID 5403 | __end_tp_report 09:40:23 INFO - PID 5403 | __start_cc_report 09:40:23 INFO - PID 5403 | _x_x_mozilla_cycle_collect,1740 09:40:23 INFO - PID 5403 | __end_cc_report 09:40:23 INFO - PID 5403 | __startTimestamp1489941623171__endTimestamp 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | ------- Summary: start ------- 09:40:23 INFO - PID 5403 | Number of tests: 14 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.13 09:40:23 INFO - PID 5403 | Values: 111.0 115.0 113.0 112.0 113.0 114.0 115.0 114.0 114.0 112.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:120.50 stddev:14.72 (12.2%) stddev-sans-first:14.65 09:40:23 INFO - PID 5403 | Values: 142.0 119.0 119.0 159.0 118.0 117.0 119.0 122.0 142.0 118.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.05 09:40:23 INFO - PID 5403 | Values: 160.0 161.0 163.0 160.0 162.0 161.0 163.0 162.0 163.0 162.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.50 stddev:13.35 (17.7%) stddev-sans-first:14.12 09:40:23 INFO - PID 5403 | Values: 78.0 78.0 74.0 116.0 74.0 74.0 77.0 74.0 91.0 74.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:83.00 stddev:8.98 (10.8%) stddev-sans-first:9.36 09:40:23 INFO - PID 5403 | Values: 84.0 83.0 101.0 84.0 83.0 83.0 97.0 83.0 83.0 106.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:40:23 INFO - PID 5403 | Values: 113.0 113.0 113.0 112.0 113.0 112.0 113.0 112.0 113.0 113.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:40:23 INFO - PID 5403 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 148.0 146.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:40:23 INFO - PID 5403 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:0.67 (1.2%) stddev-sans-first:0.71 09:40:23 INFO - PID 5403 | Values: 55.0 55.0 55.0 54.0 55.0 55.0 56.0 56.0 56.0 56.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 09:40:23 INFO - PID 5403 | Values: 55.0 55.0 54.0 54.0 54.0 54.0 54.0 54.0 53.0 54.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.00 stddev:2.47 (3.1%) stddev-sans-first:2.59 09:40:23 INFO - PID 5403 | Values: 81.0 81.0 80.0 80.0 78.0 86.0 78.0 78.0 78.0 79.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:128.00 stddev:13.01 (10.2%) stddev-sans-first:13.67 09:40:23 INFO - PID 5403 | Values: 137.0 132.0 124.0 128.0 128.0 122.0 128.0 127.0 167.0 126.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:180.00 stddev:4.16 (2.3%) stddev-sans-first:3.64 09:40:23 INFO - PID 5403 | Values: 188.0 178.0 181.0 179.0 179.0 190.0 179.0 180.0 180.0 179.0 09:40:23 INFO - PID 5403 | 09:40:23 INFO - PID 5403 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.35 (2.1%) stddev-sans-first:1.42 09:40:23 INFO - PID 5403 | Values: 66.0 64.0 64.0 65.0 66.0 67.0 65.0 64.0 66.0 68.0 09:40:23 INFO - PID 5403 | -------- Summary: end -------- 09:40:23 INFO - PID 5403 | 09:40:24 INFO - TEST-INFO | 5403: exit 0 09:40:24 INFO - TEST-OK | kraken | took 61043ms 09:40:24 INFO - SUITE-END | took 1177s 09:40:24 INFO - Completed test suite (00:19:37) 09:40:24 INFO - javascript benchmark 09:40:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7517.294242565361, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3231.40219059474, 3250.6099268087833, 3273.6235332936703, 3277.2298509850198, 3304.6199687035996, 5439.102548079562, 5535.114381698929, 5535.972320138399, 5559.054960656688, 5565.387807341194, 5460.153676180188, 5508.7521061552225, 5515.503604675589, 5582.302212223472, 5623.184638227454, 4991.326170966928, 5037.798488060474, 5040.1935690289565, 5062.772175252123, 5088.313077734496, 4964.379452568429, 5019.196928491442, 5038.244263360507, 5093.949060509403, 5098.719570423626, 4897.902041959176, 5016.824411145592, 5031.094403007493, 5062.392512898439, 5126.666766660173, 4881.219004959189, 4895.339129217546, 4962.131626965267, 5003.924941125886, 5010.223415370623, 4943.184374578191, 4947.158982972904, 4949.158643030693, 4983.551480366748, 5015.398152221756, 5481.698506582136, 5530.391656917735, 5537.778488860441, 5538.889222215533, 5547.223388725582, 5808.97095514524, 5855.590108692393, 5859.326177489601, 5881.970590147064, 5959.702014899238, 6017.638941663514, 6040.57715959883, 6056.848578785522, 6097.664628445434, 6102.481289090432, 5584.944150558483, 5600.047991841438, 5602.52378547828, 5602.859928501779, 5613.691246981373, 5902.0, 6011.248593925759, 6159.753609855646, 6161.599496032743, 6175.228096487939, 5627.212190293362, 5769.480746732815, 5892.911606325909, 5923.911141332884, 5930.199423077829, 5805.796797112165, 5823.854403639943, 5828.154917536934, 5828.59199856006, 5831.154482599999, 5855.2973643163095, 5922.318933322636, 5967.0, 5967.015442452033, 5976.462118409364, 5935.554833387513, 5952.345242023374, 5953.315368732651, 5974.641521508724, 5994.3705910878725, 4499.797509112098, 4542.954570454253, 4582.770861456914, 4584.33527138563, 4589.0821835633005, 4045.0, 4088.7342322749523, 4090.877273681794, 4102.487189101363, 4117.3618089196225, 5617.9438205618235, 5642.435756424325, 5643.633163844337, 5649.12438572022, 5697.743601537941, 5794.565407594448, 5987.401259873977, 5994.9700251499335, 5995.970020149872, 6006.879862402815, 5484.287042684426, 5554.0, 5576.470235327637, 5576.776928922797, 5579.497845193952, 5009.924851127236, 5035.672681275705, 5049.040682270357, 5055.595552355802, 5057.544820966131, 6101.519919784808, 6152.3540028297275, 6173.98129308677, 6174.25908890936, 6180.289266734293, 6023.45788879003, 6025.0, 6029.51763858899, 6040.818775436744, 6058.151858739692, 6015.789447369321, 6229.3770622937345, 6280.246370435577, 6281.9948808190475, 6287.811365659038, 5779.133130030529, 5801.071828507418, 5911.704414779158, 5922.615030023035, 5926.111083337534, 6003.589156548295, 6013.398660133952, 6014.969925150346, 6017.458428741435, 6023.638581684938, 5815.4184581543195, 5831.329397119385, 5842.328132264844, 5843.181954526284, 5855.151003104611, 5620.4660557247, 5664.178694089416, 5757.251557297524, 5783.595148339744, 5811.854703632274, 5816.069428891357, 5828.358880523224, 5837.474627283565, 5843.649381037151, 5849.61977471463, 5859.384764599656, 5884.0585506318785, 5889.0, 5889.764409423575, 5891.263592050994, 5536.7785288588, 5560.527355174693, 5625.831225063254, 5626.212330273846, 5705.885882282248], "value": 5475.8306865965615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.4806498995773, 719.1514013464111, 743.7359737293259, 802.137701970382, 812.6627449608413, 30284.0, 35389.46915796265, 36016.63983360166, 36167.45748813763, 36227.81886090566, 41657.791711041406, 42247.0, 42248.577514224846, 42296.365554516706, 42426.7878660607, 7137.429005679559, 8499.107593702665, 8705.521196334199, 8716.607752651114, 8727.301815854747, 7294.927050729504, 9843.803123937552, 10042.0, 10059.949700251525, 10080.495975201247, 10263.332883362558, 10300.22748293881, 10333.741656458611, 10334.173266138729, 10336.276460647758, 7206.711731530732, 10236.692899213036, 10254.7436314092, 10273.02406271403, 10291.382517048964, 7253.746118885813, 9790.314677972545, 9986.101250887456, 9989.0, 10007.099361057468, 35926.46110308347, 36218.27563448743, 40340.59659403398, 40482.19035619301, 40547.39178912289, 3539.2490445923345, 3547.875824346161, 3566.1797786508987, 3624.474451204587, 3761.247750449921, 25812.225633230846, 25965.0, 26241.868790656117, 26517.602235966475, 26818.329541761417, 3968.523777146732, 3992.5608183099835, 4045.554988951213, 4060.1270726793587, 4083.775392353419, 158415.0, 158443.0, 158609.0, 158919.0, 159092.0, 42068.158636827095, 42151.0, 42188.578114218464, 42195.57804421917, 42203.0, 44437.55562444399, 44437.55562444399, 44461.66615001555, 44464.0, 44505.33242001373, 12661.113722039368, 12677.809832852514, 12678.619641410773, 12803.359832008362, 12807.103502754904, 9129.224015958718, 9134.954325228331, 9994.950025249973, 9998.100170984646, 10005.79988400228, 13610.183388996691, 13706.177629342272, 13820.170789752447, 13828.792568111487, 13862.098963567336, 5575.856949325379, 5786.537077033911, 5804.65172089676, 5807.390224026416, 5872.647641141545, 1113.8663360396804, 1140.9087273018138, 1149.6665966869514, 1161.3206274329611, 1208.800547909585, 24333.513329733305, 24570.0, 25759.48481030369, 26089.608655870175, 26186.86906565455, 1236.0111910471767, 1238.3993763024926, 1257.3336131850135, 1273.5478904988781, 1281.711614886643, 13394.93302533481, 13661.931690341484, 13734.381952812148, 13794.862051379358, 13975.301234938212, 310711.0, 311265.0, 312725.6001839796, 313022.0, 313193.0, 53157.734211328694, 53270.467295327326, 53347.733261333444, 53350.7332463343, 53392.73303633535, 1362.9182249065088, 1416.221078406872, 1422.7439060968918, 1430.1562078373809, 1520.7338715724704, 24245.03019879185, 24305.635415468427, 25376.74623253818, 25377.49245015126, 25478.74521254838, 39490.605093948696, 43357.78321108374, 43770.56229437792, 43809.78095109504, 43817.12365752731, 342630.0, 344556.0, 347200.0, 347232.0, 347931.0, 34563.481547776304, 34931.47602786011, 35122.47316290207, 35410.46884296686, 35906.28187436184, 41843.16313673648, 42771.7861410691, 43133.352999705654, 43554.34668479912, 43563.0, 36048.0, 36199.457008144374, 36431.635683642824, 36457.08857278589, 36606.267874642894, 35687.82156089307, 35712.10719732028, 35909.1022724434, 36014.639853601126, 36066.45900311445, 35937.28125437424, 36433.0, 36440.635593643725, 36596.63403365932, 36923.63076369202, 30384.240393990327, 32012.839935800173, 32446.83776581102, 32656.673433265365, 32892.50661240131, 24767.628485573092, 25066.498670026864, 25322.620160697974, 25501.61747573751, 25724.871375643], "value": 17737.440868667538, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.12381460733, 415.88266512278676, 431.3981995116811, 443.1314623338257, 446.0944283105296, 16228.756568651464, 19693.387384595393, 21630.783692163073, 21745.456363590878, 21890.562188756234, 20710.171593136292, 20995.58008839826, 21531.569368612618, 21653.67519487209, 21677.45806354842, 5241.030409374278, 5867.295924489067, 6680.83297917551, 6686.197656281253, 6707.832304192385, 6511.9348806512035, 6663.600183988976, 6665.000249962496, 6665.533412661116, 6673.966630166842, 6382.553221274513, 6568.277489476154, 6584.736610535573, 6595.274519802818, 6603.471722262231, 6521.119648847392, 6527.347265273482, 6527.902081468782, 6533.738650453976, 7302.780916572511, 7414.0, 7452.143003554552, 7457.477976541644, 7457.589832559207, 7472.476926615139, 23253.30240092783, 23299.301020969397, 23365.182218622438, 23469.178578749834, 23730.0, 3141.748660107186, 3179.8092114473206, 3206.150370151912, 3213.903582892517, 3238.4170849247125, 15336.539903802905, 15637.765433518507, 15774.290156942965, 15804.446844360393, 16178.75731864023, 2128.553003869174, 2166.6425039868404, 2178.194068194762, 2178.7821217878245, 2190.893598732646, 100585.0, 109794.45102774515, 109980.4500977498, 110173.0, 112915.80431326613, 14970.550883473514, 17747.201375938115, 17918.91040544789, 18363.35728249505, 18614.627707445776, 30081.63816246632, 30240.69759302379, 30260.69739302623, 30340.0, 30381.088567343017, 7809.453338266267, 8127.715529956541, 8545.316374690134, 8864.8670269946, 8966.506842123616, 6851.691673874688, 6973.825654358681, 7027.982795100861, 7150.141982962078, 7174.569525828467, 9626.229901607854, 9635.470049147365, 9881.873238591976, 9885.703428897144, 9920.206383489447, 3300.4389253826907, 3533.2933413316923, 3806.4480650306105, 3942.0, 4018.3369743991916, 846.5428668519054, 870.629982257538, 882.2633101360436, 882.748416701237, 898.9011208767031, 20832.687509687366, 21821.781782182294, 22177.55644887093, 22207.66688499674, 22225.66646001245, 864.8270345930839, 873.2664561768145, 875.0767939823547, 886.1360173830464, 889.7908991387019, 11356.602518911799, 11927.403629818475, 12015.579454719043, 12118.636440906786, 12131.818022729665, 653911.0, 678596.0, 682184.0, 687296.0, 697882.0, 111781.0, 116882.41558792321, 117928.41035794938, 119539.40230298793, 120702.0, 1226.399064458427, 1263.6398626391435, 1289.3295486347236, 1296.598054603076, 1300.9349532523336, 19433.902830485757, 19769.505762356057, 20574.074166662238, 20592.79407205909, 20706.378808635764, 34211.48682769711, 44752.77623611861, 50473.99052018866, 50571.24143137782, 51417.916789553674, 54686.72656636692, 55308.0, 55774.721126394106, 55789.72105139448, 56564.717176413855, 11437.693960372231, 11985.64043078709, 12382.504699811907, 12709.17390369623, 12829.743405131658, 14279.286035698382, 14413.351399186851, 14476.348564314841, 14877.776833347292, 15026.0, 11843.58547450835, 11900.880991189977, 12648.494060237856, 12710.936445317715, 12965.740685186054, 11396.886031139915, 12138.939305303416, 12261.203021803554, 12480.126391152533, 12530.37348132608, 11601.477933492844, 12974.935125324313, 13229.0, 13261.933690331487, 13465.730685386434, 12324.260544367367, 12331.876681233432, 12511.124221304786, 12583.559575414816, 12926.870731292567, 12407.0, 12594.874051259736, 12786.68033299175, 13123.737525249251, 13141.868581314448], "value": 11129.314890808992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.6612262241003, 1509.554681368996, 1516.476815498653, 1525.9999999999993, 1608.871290296776, 10319.174466042718, 10747.570097196112, 10769.676909692702, 10774.353538787678, 10776.73058173545, 10342.0, 10402.271840971134, 10717.785644287129, 10731.517081731321, 10735.83896241555, 2180.7819218078225, 2217.2018073493527, 2223.321886824526, 2226.487907781211, 2229.442639340165, 2140.475583482041, 2165.0906619219886, 2173.076442511934, 2184.3665337052234, 2187.354730354541, 2098.244631932511, 2102.0645812613393, 2116.460302622837, 2117.301290574107, 2129.4356995396156, 2102.358780571925, 2111.4510227340925, 2146.967795483069, 2151.0535364439597, 2153.203314773536, 2039.8062184092487, 2160.4274867160216, 2160.5030842906062, 2164.3615133535645, 2165.447810808234, 10944.343339399662, 11028.44857757117, 11049.613263535817, 11062.446877656166, 11065.834012489819, 5166.870828229286, 5352.0, 5386.326709161355, 5391.245225668409, 5392.757325920343, 3158.873645054195, 3319.5684561006915, 3383.8646454141804, 3391.559097317333, 3408.2672225471642, 1115.972100697481, 1155.7110722319421, 1160.2980196980789, 1222.2422098299023, 1225.307701148857, 13505.12216705911, 13866.029377943549, 14922.925385373112, 15004.474843380429, 15007.099574025484, 4197.874063778091, 4325.653947684241, 4388.824447022147, 4436.157130145262, 4446.133004064176, 11537.0, 11537.538498460135, 11552.711182220512, 11555.711107222218, 11557.595484158182, 4518.299663552155, 4689.812407503661, 5105.770240339194, 5261.158214685632, 5375.091842395971, 4777.187878060738, 4859.416869975626, 4869.123557759637, 4871.902561948741, 4873.634477414208, 3499.9125021874656, 3522.5244591980268, 3561.4301711726516, 3569.196930690574, 3570.8571657133425, 895.9417637853519, 912.5756523216736, 913.0869130869131, 918.8851393575803, 927.939683920543, 972.3873959405529, 977.8142152990908, 1000.1948431512557, 1004.9748756281006, 1016.598443614768, 6515.4787616990525, 6519.250286217051, 6581.901271480931, 6684.699188536528, 6687.130673012477, 966.4877614864133, 978.2858513285341, 991.375433476905, 1006.6828948881079, 1022.0239671114068, 3706.369917114097, 4487.6634252431195, 4717.386739723883, 4722.0, 4834.564889159992, 13032.80450793239, 13196.472141114247, 13265.270410127536, 13283.667908302177, 13410.530631428048, 12821.0, 12954.028447866447, 13185.274809885548, 13793.034487585772, 13860.861391386159, 10027.197824174049, 10125.240606954509, 10183.08352248316, 10291.639792607222, 10301.896981030244, 6448.5163612730175, 6530.281669016499, 6559.0, 6561.901263736892, 6761.121054263111, 11396.373199474108, 11548.422578870855, 12294.139410241198, 12342.382880855741, 12371.0, 11486.65540033767, 11633.0, 11655.650330490098, 11656.0, 11668.88331116678, 8372.497650141011, 8654.78363040929, 8663.26362259215, 8667.263282621048, 8694.130586941255, 9330.95334523323, 9432.434053956784, 9584.37701549397, 9592.328537002342, 9600.231981441608, 8428.915710842813, 8446.788830279293, 8540.572971351532, 8788.209061184523, 8820.91179088201, 7985.321247694011, 8025.518468891885, 8028.036635603765, 8572.185642363966, 8644.481331120152, 7182.60495672743, 7196.676149573386, 7263.636818158965, 7306.634668266672, 7345.926540734525, 5684.971575142098, 5703.543716502752, 5712.914306285326, 5789.3921138279875, 5964.7912323068485, 5702.572307076903, 5751.597388182954, 5752.079667253387, 5867.911981320198, 5925.489000305067], "value": 4903.4543615488965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.64965180988906, 396.540013584242, 403.26203048421394, 411.42400639105267, 412.85550057479884, 5716.914246286309, 5739.282589676291, 5760.36635970043, 5787.421257874215, 5814.854628634286, 5489.286392768934, 5669.09294512878, 5682.318121825377, 5685.1756495308155, 5699.68651724155, 4495.842645507408, 4507.188706032921, 4518.502964673883, 4521.796519156645, 4533.705309154894, 4497.123061003105, 4516.503184649686, 4523.0, 4537.2740361542155, 4550.314245461171, 4424.84513042042, 4479.686421950465, 4483.439570053743, 4490.946156613501, 4512.16524942885, 4462.866114016585, 4481.512760417967, 4502.122086193193, 4508.66185036122, 4515.977420112911, 4040.333344998072, 4068.776217308047, 4070.4708387909677, 4089.345704687236, 4090.404742150534, 5425.186222066682, 5508.504234618904, 5568.638038527482, 5570.220169176318, 5580.720963951786, 5250.8687282817855, 5254.343207099113, 5265.289185959885, 5281.181416880389, 5303.124984377573, 6039.667818269993, 6042.274927008743, 6049.364816694272, 6054.96972515139, 6083.148359229712, 4539.704919180276, 4541.977290113561, 4551.726896386194, 4633.189191891405, 4841.489919808111, 5988.706211583655, 6182.907256391158, 6203.193584833988, 6298.49612031041, 6356.014817703217, 5355.785768569213, 5425.348958124971, 5493.505584497414, 5498.422665620053, 5506.0, 5530.557555395559, 5561.972190139023, 5566.749496272678, 5583.832485025456, 5620.615919582732, 5630.324361076697, 5667.574931880125, 5733.627314224562, 5734.655920644775, 5743.712814359265, 6041.909371359433, 6064.0297552392285, 6070.696465176724, 6090.786822461193, 6092.573519853657, 1887.5469887226966, 1944.2417457191707, 1952.384998725397, 1955.3742802303127, 1965.744453221072, 1559.7270477666364, 1576.393088660874, 1591.6020994751311, 1604.5507257967554, 1619.1256721370328, 5815.476607105381, 5850.5904586678525, 5874.970625146932, 5878.29460464747, 5894.233749612523, 6213.502919766408, 6218.31598524162, 6232.594881332699, 6241.313455519889, 6253.6560489173535, 5643.548516118701, 5755.165501002331, 5808.477237048686, 5830.475257226785, 5883.294004719461, 3271.6237632672073, 3273.4435146025235, 3286.14560214346, 3302.240484688535, 3304.0253125328086, 6302.676437948082, 6427.143713771699, 6480.157579514633, 6485.794829757905, 6517.055027021055, 6225.564210505312, 6261.279952805395, 6289.371062893674, 6322.146510221061, 6327.462165715873, 6405.871882562323, 6429.228492580826, 6430.935690643034, 6460.3216662250725, 6468.482521398371, 6017.428344307284, 6189.35011823752, 6202.503799696104, 6210.130581718653, 6219.222597175353, 5846.736896839568, 6034.456898879122, 6035.6076855004285, 6154.3845615438095, 6158.3225845157895, 5945.940540594712, 6317.778877739257, 6325.652089135142, 6363.427291543783, 6447.516436267205, 4858.684185527929, 5422.783088676409, 5428.267183930119, 5453.7818487260065, 5464.453554644504, 6193.504519638508, 6214.347493513297, 6231.781887633911, 6243.937560624336, 6249.156363890998, 5963.761449541969, 5980.910286345796, 5995.130706047686, 6021.969890150521, 6045.848853778515, 5787.276590426197, 6010.038393856962, 6019.699015049143, 6028.246469191351, 6036.154938308729, 6031.215941927521, 6041.9395806041375, 6055.485283750931, 6061.212042434456, 6063.454289114001, 5878.500327472213, 6058.939410605837, 6108.938910611015, 6127.785527506516, 6139.877202456015, 5895.823125306248, 5905.734241959036, 5909.674967876808, 5914.556408269311, 5918.201042859159], "value": 4822.570263217709, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.6943487887407, 765.7664412354229, 806.661202295036, 814.2061490047201, 826.5247482697448, 15682.451114211004, 16459.423920162797, 16470.17649117542, 16502.257398417085, 16504.422345217903, 12965.675858103552, 12979.22124672518, 12982.740345193091, 13204.405801738922, 13582.524611638595, 831.5094094484253, 852.5438890193748, 857.8927634045745, 859.9011113721908, 860.849351363512, 830.8795224692416, 847.220557087481, 848.8896443462341, 849.2102344819316, 849.5030407211758, 884.8274586455643, 893.1648908270788, 893.6023469556018, 894.0790985285166, 894.7986702991807, 878.9868151977694, 885.583775625455, 885.9025507194235, 887.1084560017174, 887.4941283468426, 642.8425035866243, 646.6217262901162, 650.0898741761524, 650.3171669746747, 650.6258901131821, 16027.0, 16416.507504774876, 17248.223829927683, 17308.82691173085, 17994.91002544979, 15949.681006379924, 16359.0, 16455.177241137895, 16633.25150368224, 16771.83228167727, 12238.632841014783, 12526.74946501065, 12662.493500259978, 12833.165844220095, 13285.202887826761, 3600.0, 3652.2330310634798, 3722.2927643747603, 3735.869244576454, 3813.3326667833016, 87141.0, 87643.0, 87690.56154719186, 87827.56086219593, 88699.0, 11780.587679431179, 11935.820962685566, 11944.641660750018, 12096.0, 12192.085593580518, 25443.87278063598, 25536.744632553808, 25623.615645764956, 25805.3548661285, 25836.478616242082, 8205.876911846328, 8222.876656850152, 8296.294814940677, 8322.292605128632, 8332.458390204618, 3893.2992061429213, 3896.688264938798, 3913.25648126855, 3948.9210215796097, 3958.1687845552183, 3417.0, 3457.3949558827103, 3502.0, 3524.0308915048568, 3541.7520773545602, 568.9644846379549, 570.7688386203595, 573.6214098694842, 574.8505388598994, 579.6000759475949, 615.30163264694, 628.5380245519631, 635.581611966472, 647.9190101237346, 670.7585269303047, 9531.37966545675, 9579.13787759105, 9779.951100244452, 9789.412635241908, 10238.46772863361, 531.9069162896477, 547.2284079447959, 553.5626854784675, 584.7251791657914, 665.890128128863, 4515.774211289422, 4674.392328997276, 4780.259059845729, 4794.065157294364, 4865.489123642037, 71346.0, 77916.22083779203, 78126.60936695394, 79246.6037669808, 81969.18030819735, 67482.3251767476, 68715.0, 69632.3036769636, 69965.0, 72070.27929720741, 50779.36750752307, 58849.99999999914, 59088.70455647694, 60118.0, 62435.0, 8646.481211127353, 9053.275737940818, 9078.54607269647, 9115.262846606089, 9144.131307525775, 40546.0, 41038.0, 41539.58460415357, 41839.37240941449, 42628.54000549953, 50286.831682179705, 51156.48843511517, 51766.74116629393, 52548.0, 53388.46611533835, 10958.178136639943, 11067.557297708323, 11241.437928103398, 11256.437178141225, 11269.71825704364, 11832.290062596272, 11973.76052478928, 12193.329366884907, 12194.085443591588, 12322.260664360167, 11602.6519204424, 12086.15396922207, 12342.227192842636, 12634.115611907446, 12636.810447843105, 11573.652790416301, 11583.536658533565, 11852.0, 11947.701307467036, 12492.500299987898, 11200.831987520356, 11759.176857619885, 12055.276683399024, 12215.633530994084, 12224.266544007389, 10021.39871607706, 10421.9478902605, 10618.309809862334, 10745.462726863781, 10794.08250298702, 8631.525266110424, 9081.364304498622, 9859.457729824642, 10118.848217276895, 10139.64511242103], "value": 7058.995011423341, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1493.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 113.0, 112.0, 113.0, 114.0, 115.0, 114.0, 114.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 119.0, 119.0, 159.0, 118.0, 117.0, 119.0, 122.0, 142.0, 118.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 160.0, 162.0, 161.0, 163.0, 162.0, 163.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 74.0, 116.0, 74.0, 74.0, 77.0, 74.0, 91.0, 74.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 101.0, 84.0, 83.0, 83.0, 97.0, 83.0, 83.0, 106.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 55.0, 55.0, 56.0, 56.0, 56.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 80.0, 78.0, 86.0, 78.0, 78.0, 78.0, 79.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 124.0, 128.0, 128.0, 122.0, 128.0, 127.0, 167.0, 126.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 178.0, 181.0, 179.0, 179.0, 190.0, 179.0, 180.0, 180.0, 179.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 65.0, 66.0, 67.0, 65.0, 64.0, 66.0, 68.0], "value": 65.5, "unit": "score"}]}]} 09:40:24 INFO - Return code: 0 09:40:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:40:24 ERROR - # TBPL SUCCESS # 09:40:24 INFO - Running post-action listener: _resource_record_post_action 09:40:24 INFO - [mozharness: 2017-03-19 16:40:24.497022Z] Finished run-tests step (success) 09:40:24 INFO - Running post-run listener: _resource_record_post_run 09:40:24 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 367443968; Read time: 16; Write time: 265268 09:40:24 INFO - TinderboxPrint: CPU usage
12.9% 09:40:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:40:24 INFO - TinderboxPrint: I/O write bytes / time
367,443,968 / 265,268 09:40:24 INFO - TinderboxPrint: CPU idle
8,297.3 (87.1%) 09:40:24 INFO - TinderboxPrint: CPU user
1,187.7 (12.5%) 09:40:24 INFO - TinderboxPrint: Swap in / out
0 / 0 09:40:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111304704; Read time: 0; Write time: 118528 09:40:24 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 255381504; Read time: 0; Write time: 146688 09:40:25 INFO - Running post-run listener: _upload_blobber_files 09:40:25 INFO - Blob upload gear active. 09:40:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:40:25 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 09:40:25 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'] 09:40:25 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 09:40:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:40:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:40:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:40:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:28 INFO - (blobuploader) - INFO - Done attempting. 09:40:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:40:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:40:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:29 INFO - (blobuploader) - INFO - Done attempting. 09:40:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:40:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:40:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:30 INFO - (blobuploader) - INFO - Done attempting. 09:40:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:40:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:40:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:31 INFO - (blobuploader) - INFO - Done attempting. 09:40:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:40:31 INFO - Return code: 0 09:40:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:40:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:40:31 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b14b829695eba3c13b7ec5c3b7851bd9a442f1c80eafad009e869441da13f1bc931b42f6fe0024a0c89c3eafa5b40356ec53ed27cef6157c92ff4de71b1dfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f604253b4d74568c87e7efbae227d130d513151cae38900d9279a059566c772122caf125c1e20b6a1b3e5715b5c6e64662544e86c79a7d46ed74feb035460f01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d72078760a362aac91313a56ad520558b226ae2ed262b064d91e58f0d231f067c2e3dbff451d78c63281f9f58c8bd44dcd3a24303fe5a8a00e52a8d00172c00d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986b1c9b3795ef61fea81618d4517f706baf2ac7f48cbdf0c24deabe565b08d5772bfd19ce4d971e55b10d6f4fd5010f207bf1aebf005fd0e94e1d66ba57c2b3"} 09:40:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:40:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:40:31 INFO - Contents: 09:40:31 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b14b829695eba3c13b7ec5c3b7851bd9a442f1c80eafad009e869441da13f1bc931b42f6fe0024a0c89c3eafa5b40356ec53ed27cef6157c92ff4de71b1dfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f604253b4d74568c87e7efbae227d130d513151cae38900d9279a059566c772122caf125c1e20b6a1b3e5715b5c6e64662544e86c79a7d46ed74feb035460f01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d72078760a362aac91313a56ad520558b226ae2ed262b064d91e58f0d231f067c2e3dbff451d78c63281f9f58c8bd44dcd3a24303fe5a8a00e52a8d00172c00d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986b1c9b3795ef61fea81618d4517f706baf2ac7f48cbdf0c24deabe565b08d5772bfd19ce4d971e55b10d6f4fd5010f207bf1aebf005fd0e94e1d66ba57c2b3"} 09:40:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:40:31 INFO - Copying logs to upload dir... 09:40:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:40:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.431259 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-19 09:40:30.955877) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-19 09:40:30.959709) ========= 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=20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491 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/3b14b829695eba3c13b7ec5c3b7851bd9a442f1c80eafad009e869441da13f1bc931b42f6fe0024a0c89c3eafa5b40356ec53ed27cef6157c92ff4de71b1dfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f604253b4d74568c87e7efbae227d130d513151cae38900d9279a059566c772122caf125c1e20b6a1b3e5715b5c6e64662544e86c79a7d46ed74feb035460f01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d72078760a362aac91313a56ad520558b226ae2ed262b064d91e58f0d231f067c2e3dbff451d78c63281f9f58c8bd44dcd3a24303fe5a8a00e52a8d00172c00d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986b1c9b3795ef61fea81618d4517f706baf2ac7f48cbdf0c24deabe565b08d5772bfd19ce4d971e55b10d6f4fd5010f207bf1aebf005fd0e94e1d66ba57c2b3"} build_url:https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011484 build_url: 'https://queue.taskcluster.net/v1/task/KXjj8ZdbSimg6OfBP5ObJg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b14b829695eba3c13b7ec5c3b7851bd9a442f1c80eafad009e869441da13f1bc931b42f6fe0024a0c89c3eafa5b40356ec53ed27cef6157c92ff4de71b1dfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f604253b4d74568c87e7efbae227d130d513151cae38900d9279a059566c772122caf125c1e20b6a1b3e5715b5c6e64662544e86c79a7d46ed74feb035460f01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d72078760a362aac91313a56ad520558b226ae2ed262b064d91e58f0d231f067c2e3dbff451d78c63281f9f58c8bd44dcd3a24303fe5a8a00e52a8d00172c00d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986b1c9b3795ef61fea81618d4517f706baf2ac7f48cbdf0c24deabe565b08d5772bfd19ce4d971e55b10d6f4fd5010f207bf1aebf005fd0e94e1d66ba57c2b3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-19 09:40:30.985782) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:40:30.986164) ========= 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=20e666a3d4cfbb71505a330e000001b4-1489940374.443280-583848491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:40:31.070498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-19 09:40:31.070858) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-19 09:40:31.071246) ========= ========= Total master_lag: 0.17 =========