builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1489593583.15 results: success (0) revision: 8c89d1991786625a64d868798281610872a2bc26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.151901) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.152394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.152677) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.167358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.167705) ========= 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=ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-15 08:59:43-- 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% 227M=0s 2017-03-15 08:59:43 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083786 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.265416) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.265744) ========= 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=ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020038 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:43.314717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-15 08:59:43.315074) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c89d1991786625a64d868798281610872a2bc26 --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 8c89d1991786625a64d868798281610872a2bc26 --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=ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-15 08:59:43,418 truncating revision to first 12 chars 2017-03-15 08:59:43,418 Setting DEBUG logging. 2017-03-15 08:59:43,418 attempt 1/10 2017-03-15 08:59:43,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c89d1991786?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-15 08:59:44,648 unpacking tar archive at: mozilla-central-8c89d1991786/testing/mozharness/ program finished with exit code 0 elapsedTime=1.543098 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-15 08:59:44.871513) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:44.871822) ========= script_repo_revision: 8c89d1991786625a64d868798281610872a2bc26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:44.872251) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:44.872520) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 08:59:44.880521) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-15 08:59:44.880789) ========= /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=ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:45 INFO - MultiFileLogger online at 20170315 08:59:45 in /builds/slave/test 08:59:45 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 08:59:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:45 INFO - {'append_to_log': False, 08:59:45 INFO - 'base_work_dir': '/builds/slave/test', 08:59:45 INFO - 'blob_upload_branch': 'Firefox', 08:59:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:45 INFO - 'branch': 'Firefox', 08:59:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:45 INFO - 'config_files': ('talos/linux_config.py',), 08:59:45 INFO - 'default_actions': ('clobber', 08:59:45 INFO - 'read-buildbot-config', 08:59:45 INFO - 'download-and-extract', 08:59:45 INFO - 'populate-webroot', 08:59:45 INFO - 'create-virtualenv', 08:59:45 INFO - 'install', 08:59:45 INFO - 'run-tests'), 08:59:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:45 INFO - 'download_minidump_stackwalk': True, 08:59:45 INFO - 'download_symbols': 'ondemand', 08:59:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:45 INFO - 'gecko_profile': False, 08:59:45 INFO - 'gecko_profile_interval': 0, 08:59:45 INFO - 'installer_path': 'installer.exe', 08:59:45 INFO - 'log_level': 'info', 08:59:45 INFO - 'log_name': 'talos', 08:59:45 INFO - 'log_to_console': True, 08:59:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:45 INFO - 'opt_config_files': (), 08:59:45 INFO - 'pip_index': False, 08:59:45 INFO - 'suite': 'dromaeojs', 08:59:45 INFO - 'system_bits': '32', 08:59:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:45 INFO - 'title': 'talos-linux64-ix-035', 08:59:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:45 INFO - 'use_talos_json': True, 08:59:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:45 INFO - 'work_dir': 'build'} 08:59:45 INFO - [mozharness: 2017-03-15 15:59:45.079128Z] Running clobber step. 08:59:45 INFO - Running pre-action listener: _resource_record_pre_action 08:59:45 INFO - Running main action method: clobber 08:59:45 INFO - rmtree: /builds/slave/test/build 08:59:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:46 INFO - Running post-action listener: _resource_record_post_action 08:59:46 INFO - [mozharness: 2017-03-15 15:59:46.040087Z] Finished clobber step (success) 08:59:46 INFO - [mozharness: 2017-03-15 15:59:46.040187Z] Running read-buildbot-config step. 08:59:46 INFO - Running pre-action listener: _resource_record_pre_action 08:59:46 INFO - Running main action method: read_buildbot_config 08:59:46 INFO - Using buildbot properties: 08:59:46 INFO - { 08:59:46 INFO - "project": "", 08:59:46 INFO - "product": "firefox", 08:59:46 INFO - "who": "ffxbld@noreply.mozilla.org", 08:59:46 INFO - "installer_path": "public/build/target.tar.bz2", 08:59:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:59:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:59:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:59:46 INFO - "stage_platform": "linux64-pgo", 08:59:46 INFO - "basedir": "/builds/slave/test", 08:59:46 INFO - "buildnumber": 77, 08:59:46 INFO - "platform": "ubuntu64_hw", 08:59:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:59:46 INFO - "slavebuilddir": "test", 08:59:46 INFO - "taskId": "OP7xGTkPQ0erSLmX-QVuhA", 08:59:46 INFO - "branch": "mozilla-central", 08:59:46 INFO - "script_repo_revision": "production", 08:59:46 INFO - "revision": "8c89d1991786625a64d868798281610872a2bc26", 08:59:46 INFO - "slavename": "talos-linux64-ix-035", 08:59:46 INFO - "repo_path": "mozilla-central" 08:59:46 INFO - } 08:59:46 INFO - Finding installer, test and symbols from parent task. 08:59:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OP7xGTkPQ0erSLmX-QVuhA'}, attempt #1 08:59:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OP7xGTkPQ0erSLmX-QVuhA'}, attempt #1 08:59:46 INFO - Task dependencies: bnwN-GwfR6y_y5esE2oOKw 08:59:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw'}, attempt #1 08:59:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2 08:59:47 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/test_packages.json 08:59:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.crashreporter-symbols.zip 08:59:47 INFO - Running post-action listener: _resource_record_post_action 08:59:47 INFO - [mozharness: 2017-03-15 15:59:47.442075Z] Finished read-buildbot-config step (success) 08:59:47 INFO - [mozharness: 2017-03-15 15:59:47.442293Z] Running download-and-extract step. 08:59:47 INFO - Running pre-action listener: _resource_record_pre_action 08:59:47 INFO - Running main action method: download_and_extract 08:59:47 INFO - mkdir: /builds/slave/test/build/tests 08:59:47 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')]} 08:59:47 INFO - trying https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.test_packages.json 08:59:47 INFO - Downloading https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:59:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:59:48 INFO - Downloaded 896 bytes. 08:59:48 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:59:48 INFO - Using the following test package requirements: 08:59:48 INFO - {u'common': [u'target.common.tests.zip'], 08:59:48 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:59:48 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:59:48 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:59:48 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:59:48 INFO - u'mozbase': [u'target.common.tests.zip'], 08:59:48 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:59:48 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:59:48 INFO - u'web-platform': [u'target.common.tests.zip', 08:59:48 INFO - u'target.web-platform.tests.zip'], 08:59:48 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:59:48 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:59:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.common.tests.zip 08:59:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:59:48 INFO - Fetch https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.common.tests.zip into memory 08:59:49 INFO - Expected file size: 60312427 08:59:49 INFO - Obtained file size: 60312427 08:59:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:59:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.common.tests.zip 08:59:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:59:59 INFO - Fetch https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.common.tests.zip into memory 09:00:00 INFO - Expected file size: 60312427 09:00:00 INFO - Obtained file size: 60312427 09:00:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.talos.tests.zip 09:00:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:00:11 INFO - Fetch https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.talos.tests.zip into memory 09:00:11 INFO - Expected file size: 14404452 09:00:11 INFO - Obtained file size: 14404452 09:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:12 INFO - trying https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2 09:00:12 INFO - Downloading https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:00:13 INFO - Downloaded 67145949 bytes. 09:00:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2 09:00:13 INFO - mkdir: /builds/slave/test/properties 09:00:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:13 INFO - Contents: 09:00:13 INFO - build_url:https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2 09:00:13 INFO - Running post-action listener: _resource_record_post_action 09:00:13 INFO - Running post-action listener: set_extra_try_arguments 09:00:13 INFO - [mozharness: 2017-03-15 16:00:13.250330Z] Finished download-and-extract step (success) 09:00:13 INFO - [mozharness: 2017-03-15 16:00:13.250427Z] Running populate-webroot step. 09:00:13 INFO - Running pre-action listener: _resource_record_pre_action 09:00:13 INFO - Running main action method: populate_webroot 09:00:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:13 INFO - '--tppagecycles', 09:00:13 INFO - '7'], 09:00:13 INFO - 'tests': ['tsvgm']}}, 09:00:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['damp', 'tps']}, 09:00:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'tests': ['damp', 'tps']}, 09:00:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['dromaeo_dom']}, 09:00:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['basic_compositor_video']}, 09:00:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['a11yr', 09:00:13 INFO - 'ts_paint', 09:00:13 INFO - 'tpaint', 09:00:13 INFO - 'sessionrestore', 09:00:13 INFO - 'sessionrestore_no_auto_restore', 09:00:13 INFO - 'tabpaint']}, 09:00:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:13 INFO - 'ts_paint', 09:00:13 INFO - 'tpaint', 09:00:13 INFO - 'sessionrestore', 09:00:13 INFO - 'sessionrestore_no_auto_restore', 09:00:13 INFO - 'tabpaint']}, 09:00:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:13 INFO - 'ts_paint', 09:00:13 INFO - 'tpaint', 09:00:13 INFO - 'sessionrestore', 09:00:13 INFO - 'sessionrestore_no_auto_restore', 09:00:13 INFO - 'tabpaint']}, 09:00:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:13 INFO - 'ts_paint', 09:00:13 INFO - 'tpaint', 09:00:13 INFO - 'sessionrestore', 09:00:13 INFO - 'sessionrestore_no_auto_restore', 09:00:13 INFO - 'tabpaint']}, 09:00:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['a11yr', 09:00:13 INFO - 'ts_paint', 09:00:13 INFO - 'tpaint', 09:00:13 INFO - 'sessionrestore', 09:00:13 INFO - 'sessionrestore_no_auto_restore', 09:00:13 INFO - 'tabpaint']}, 09:00:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['a11yr', 09:00:13 INFO - 'ts_paint', 09:00:13 INFO - 'tpaint', 09:00:13 INFO - 'sessionrestore', 09:00:13 INFO - 'sessionrestore_no_auto_restore', 09:00:13 INFO - 'tabpaint']}, 09:00:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['tsvgx', 09:00:13 INFO - 'tsvgr_opacity', 09:00:13 INFO - 'tart', 09:00:13 INFO - 'tscrollx', 09:00:13 INFO - 'cart', 09:00:13 INFO - 'tsvg_static']}, 09:00:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:13 INFO - 'tsvgr_opacity', 09:00:13 INFO - 'tart', 09:00:13 INFO - 'tscrollx', 09:00:13 INFO - 'cart', 09:00:13 INFO - 'tsvg_static']}, 09:00:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'talos_options': ['--disable-e10s'], 09:00:13 INFO - 'tests': ['tp5o']}, 09:00:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:00:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'talos_options': ['--disable-e10s', 09:00:13 INFO - '--xperf_path', 09:00:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:13 INFO - 'tests': ['tp5n']}, 09:00:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:00:13 INFO - 'talos_options': ['--xperf_path', 09:00:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:13 INFO - 'tests': ['tp5n']}}, 09:00:13 INFO - 'talos.zip': {'path': '', 09:00:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:13 INFO - Running post-action listener: _resource_record_post_action 09:00:13 INFO - [mozharness: 2017-03-15 16:00:13.263496Z] Finished populate-webroot step (success) 09:00:13 INFO - [mozharness: 2017-03-15 16:00:13.263589Z] Running create-virtualenv step. 09:00:13 INFO - Running pre-action listener: _resource_record_pre_action 09:00:13 INFO - Running main action method: create_virtualenv 09:00:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:00:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:13 INFO - Using real prefix '/usr' 09:00:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:15 INFO - Installing pip.................done. 09:00:15 INFO - Return code: 0 09:00:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:00:16 INFO - Reading from file tmpfile_stdout 09:00:16 INFO - Output received: 09:00:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:00:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00: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:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:16 INFO - Using env: {'DISPLAY': ':0', 09:00:16 INFO - 'HOME': '/home/cltbld', 09:00:16 INFO - 'LANG': 'en_US.UTF-8', 09:00:16 INFO - 'LANGUAGE': 'en_US:en', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MAIL': '/var/mail/cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SHLVL': '1', 09:00:16 INFO - 'TERM': 'linux', 09:00:16 INFO - 'TMOUT': '86400', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - '_': '/tools/buildbot/bin/python'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:00:16 INFO - Cleaning up... 09:00:16 INFO - Return code: 0 09:00:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00: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:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:00:16 INFO - Using env: {'DISPLAY': ':0', 09:00:16 INFO - 'HOME': '/home/cltbld', 09:00:16 INFO - 'LANG': 'en_US.UTF-8', 09:00:16 INFO - 'LANGUAGE': 'en_US:en', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MAIL': '/var/mail/cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SHLVL': '1', 09:00:16 INFO - 'TERM': 'linux', 09:00:16 INFO - 'TMOUT': '86400', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - '_': '/tools/buildbot/bin/python'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Downloading/unpacking psutil>=3.1.1 09:00: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:00: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:00: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:00: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:00: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:00: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:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:18 INFO - Installing collected packages: psutil 09:00:18 INFO - Running setup.py install for psutil 09:00:18 INFO - building 'psutil._psutil_linux' extension 09:00:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:00:18 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:00:18 INFO - building 'psutil._psutil_posix' extension 09:00:18 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:00:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:00:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:19 INFO - Successfully installed psutil 09:00:19 INFO - Cleaning up... 09:00:19 INFO - Return code: 0 09:00:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:00:19 INFO - Using env: {'DISPLAY': ':0', 09:00:19 INFO - 'HOME': '/home/cltbld', 09:00:19 INFO - 'LANG': 'en_US.UTF-8', 09:00:19 INFO - 'LANGUAGE': 'en_US:en', 09:00:19 INFO - 'LOGNAME': 'cltbld', 09:00:19 INFO - 'MAIL': '/var/mail/cltbld', 09:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:19 INFO - 'MOZ_NO_REMOTE': '1', 09:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:19 INFO - 'NO_EM_RESTART': '1', 09:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:19 INFO - 'PWD': '/builds/slave/test', 09:00:19 INFO - 'SHELL': '/bin/bash', 09:00:19 INFO - 'SHLVL': '1', 09:00:19 INFO - 'TERM': 'linux', 09:00:19 INFO - 'TMOUT': '86400', 09:00:19 INFO - 'USER': 'cltbld', 09:00:19 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:19 INFO - '_': '/tools/buildbot/bin/python'} 09:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:21 INFO - Installing collected packages: mozsystemmonitor 09:00:21 INFO - Running setup.py install for mozsystemmonitor 09:00:21 INFO - Successfully installed mozsystemmonitor 09:00:21 INFO - Cleaning up... 09:00:21 INFO - Return code: 0 09:00:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00: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:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:00:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:00:21 INFO - Using env: {'DISPLAY': ':0', 09:00:21 INFO - 'HOME': '/home/cltbld', 09:00:21 INFO - 'LANG': 'en_US.UTF-8', 09:00:21 INFO - 'LANGUAGE': 'en_US:en', 09:00:21 INFO - 'LOGNAME': 'cltbld', 09:00:21 INFO - 'MAIL': '/var/mail/cltbld', 09:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:21 INFO - 'MOZ_NO_REMOTE': '1', 09:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:21 INFO - 'NO_EM_RESTART': '1', 09:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:21 INFO - 'PWD': '/builds/slave/test', 09:00:21 INFO - 'SHELL': '/bin/bash', 09:00:21 INFO - 'SHLVL': '1', 09:00:21 INFO - 'TERM': 'linux', 09:00:21 INFO - 'TMOUT': '86400', 09:00:21 INFO - 'USER': 'cltbld', 09:00:21 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:21 INFO - '_': '/tools/buildbot/bin/python'} 09:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:21 INFO - Downloading/unpacking jsonschema==2.5.1 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:24 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:24 INFO - warning: no files found matching '*.txt' 09:00:24 INFO - no previously-included directories found matching 'build' 09:00:24 INFO - no previously-included directories found matching 'dist' 09:00:24 INFO - no previously-included directories found matching '.git*' 09:00:24 INFO - Installing collected packages: jsonschema, functools32 09:00:24 INFO - Running setup.py install for functools32 09:00:24 INFO - warning: no files found matching '*.txt' 09:00:24 INFO - no previously-included directories found matching 'build' 09:00:24 INFO - no previously-included directories found matching 'dist' 09:00:24 INFO - no previously-included directories found matching '.git*' 09:00:24 INFO - Successfully installed jsonschema functools32 09:00:24 INFO - Cleaning up... 09:00:24 INFO - Return code: 0 09:00:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00: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:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:00:24 INFO - Using env: {'DISPLAY': ':0', 09:00:24 INFO - 'HOME': '/home/cltbld', 09:00:24 INFO - 'LANG': 'en_US.UTF-8', 09:00:24 INFO - 'LANGUAGE': 'en_US:en', 09:00:24 INFO - 'LOGNAME': 'cltbld', 09:00:24 INFO - 'MAIL': '/var/mail/cltbld', 09:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:24 INFO - 'MOZ_NO_REMOTE': '1', 09:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:24 INFO - 'NO_EM_RESTART': '1', 09:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:24 INFO - 'PWD': '/builds/slave/test', 09:00:24 INFO - 'SHELL': '/bin/bash', 09:00:24 INFO - 'SHLVL': '1', 09:00:24 INFO - 'TERM': 'linux', 09:00:24 INFO - 'TMOUT': '86400', 09:00:24 INFO - 'USER': 'cltbld', 09:00:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:24 INFO - '_': '/tools/buildbot/bin/python'} 09:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:24 INFO - Cleaning up... 09:00:24 INFO - Return code: 0 09:00:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00: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:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:24 INFO - Using env: {'DISPLAY': ':0', 09:00:24 INFO - 'HOME': '/home/cltbld', 09:00:24 INFO - 'LANG': 'en_US.UTF-8', 09:00:24 INFO - 'LANGUAGE': 'en_US:en', 09:00:24 INFO - 'LOGNAME': 'cltbld', 09:00:24 INFO - 'MAIL': '/var/mail/cltbld', 09:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:24 INFO - 'MOZ_NO_REMOTE': '1', 09:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:24 INFO - 'NO_EM_RESTART': '1', 09:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:24 INFO - 'PWD': '/builds/slave/test', 09:00:24 INFO - 'SHELL': '/bin/bash', 09:00:24 INFO - 'SHLVL': '1', 09:00:24 INFO - 'TERM': 'linux', 09:00:24 INFO - 'TMOUT': '86400', 09:00:24 INFO - 'USER': 'cltbld', 09:00:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:24 INFO - '_': '/tools/buildbot/bin/python'} 09:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00: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:00: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:00: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:00: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:00: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:00: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:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:27 INFO - Downloading docopt-0.6.1.tar.gz 09:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:27 INFO - Running setup.py install for blobuploader 09:00:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:27 INFO - Running setup.py install for requests 09:00:28 INFO - Running setup.py install for docopt 09:00:28 INFO - Successfully installed blobuploader requests docopt 09:00:28 INFO - Cleaning up... 09:00:28 INFO - Return code: 0 09:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:28 INFO - Using env: {'DISPLAY': ':0', 09:00:28 INFO - 'HOME': '/home/cltbld', 09:00:28 INFO - 'LANG': 'en_US.UTF-8', 09:00:28 INFO - 'LANGUAGE': 'en_US:en', 09:00:28 INFO - 'LOGNAME': 'cltbld', 09:00:28 INFO - 'MAIL': '/var/mail/cltbld', 09:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:28 INFO - 'MOZ_NO_REMOTE': '1', 09:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:28 INFO - 'NO_EM_RESTART': '1', 09:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:28 INFO - 'PWD': '/builds/slave/test', 09:00:28 INFO - 'SHELL': '/bin/bash', 09:00:28 INFO - 'SHLVL': '1', 09:00:28 INFO - 'TERM': 'linux', 09:00:28 INFO - 'TMOUT': '86400', 09:00:28 INFO - 'USER': 'cltbld', 09:00:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:28 INFO - '_': '/tools/buildbot/bin/python'} 09:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:28 INFO - Running setup.py (path:/tmp/pip-3rkuQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:28 INFO - Running setup.py (path:/tmp/pip-MCd5NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:28 INFO - Running setup.py (path:/tmp/pip-D1XKmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:28 INFO - Running setup.py (path:/tmp/pip-cCPTz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:28 INFO - Running setup.py (path:/tmp/pip-r7PDJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:28 INFO - Running setup.py (path:/tmp/pip-35JxMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:28 INFO - Running setup.py (path:/tmp/pip-nYFY1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:28 INFO - Running setup.py (path:/tmp/pip-JSkGzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:29 INFO - Running setup.py (path:/tmp/pip-eWUVHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:29 INFO - Running setup.py (path:/tmp/pip-copFUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:29 INFO - Running setup.py (path:/tmp/pip-_qI7Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:29 INFO - Running setup.py (path:/tmp/pip-sckYo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:29 INFO - Running setup.py (path:/tmp/pip-8WX7Hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:29 INFO - Running setup.py (path:/tmp/pip-IEKAJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:29 INFO - Running setup.py (path:/tmp/pip-z5GmOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:29 INFO - Running setup.py (path:/tmp/pip-5dlOuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:29 INFO - Running setup.py (path:/tmp/pip-VekGNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:29 INFO - Running setup.py install for manifestparser 09:00:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Running setup.py install for mozcrash 09:00:29 INFO - Running setup.py install for mozdebug 09:00:29 INFO - Running setup.py install for mozdevice 09:00:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozfile 09:00:30 INFO - Running setup.py install for mozhttpd 09:00:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozinfo 09:00:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozInstall 09:00:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozleak 09:00:30 INFO - Running setup.py install for mozlog 09:00:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for moznetwork 09:00:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozprocess 09:00:30 INFO - Running setup.py install for mozprofile 09:00:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:31 INFO - Running setup.py install for mozrunner 09:00:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:31 INFO - Running setup.py install for mozscreenshot 09:00:31 INFO - Running setup.py install for moztest 09:00:31 INFO - Running setup.py install for mozversion 09:00:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:31 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:31 INFO - Running setup.py (path:/tmp/pip-Me6LEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:31 INFO - Running setup.py (path:/tmp/pip-v4dFMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:31 INFO - Running setup.py (path:/tmp/pip-_BpItz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:31 INFO - Running setup.py (path:/tmp/pip-yi3Ioy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:32 INFO - Running setup.py (path:/tmp/pip-RwWvvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:32 INFO - Running setup.py (path:/tmp/pip-ZluLw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:32 INFO - Running setup.py (path:/tmp/pip-tUQRfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:32 INFO - Running setup.py (path:/tmp/pip-x167oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:32 INFO - Running setup.py (path:/tmp/pip-95nN_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:32 INFO - Running setup.py (path:/tmp/pip-Go1v2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:32 INFO - Running setup.py (path:/tmp/pip-U0ZwEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:32 INFO - Running setup.py (path:/tmp/pip-SO2QbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:32 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:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:32 INFO - Running setup.py (path:/tmp/pip-EoztmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:32 INFO - Running setup.py (path:/tmp/pip-QxeEpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:32 INFO - Running setup.py (path:/tmp/pip-7E9S6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:32 INFO - Running setup.py (path:/tmp/pip-z5OJXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:32 INFO - Running setup.py (path:/tmp/pip-yC_cdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - Downloading blessings-1.6.tar.gz 09:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:35 INFO - Installing collected packages: blessings 09:00:35 INFO - Running setup.py install for blessings 09:00:35 INFO - Successfully installed blessings 09:00:35 INFO - Cleaning up... 09:00:35 INFO - Return code: 0 09:00:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:35 INFO - Reading from file tmpfile_stdout 09:00:35 INFO - Current package versions: 09:00:35 INFO - argparse == 1.2.1 09:00:35 INFO - blessings == 1.6 09:00:35 INFO - blobuploader == 1.2.4 09:00:35 INFO - docopt == 0.6.1 09:00:35 INFO - functools32 == 3.2.3-2 09:00:35 INFO - jsonschema == 2.5.1 09:00:35 INFO - manifestparser == 1.1 09:00:35 INFO - mozInstall == 1.12 09:00:35 INFO - mozcrash == 1.0 09:00:35 INFO - mozdebug == 0.1 09:00:35 INFO - mozdevice == 0.49 09:00:35 INFO - mozfile == 1.2 09:00:35 INFO - mozhttpd == 0.7 09:00:35 INFO - mozinfo == 0.9 09:00:35 INFO - mozleak == 0.1 09:00:35 INFO - mozlog == 3.4 09:00:35 INFO - moznetwork == 0.27 09:00:35 INFO - mozprocess == 0.25 09:00:35 INFO - mozprofile == 0.28 09:00:35 INFO - mozrunner == 6.13 09:00:35 INFO - mozscreenshot == 0.1 09:00:35 INFO - mozsystemmonitor == 0.3 09:00:35 INFO - moztest == 0.8 09:00:35 INFO - mozversion == 1.4 09:00:35 INFO - psutil == 3.1.1 09:00:35 INFO - requests == 1.2.3 09:00:35 INFO - wsgiref == 0.1.2 09:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:35 INFO - Using env: {'DISPLAY': ':0', 09:00:35 INFO - 'HOME': '/home/cltbld', 09:00:35 INFO - 'LANG': 'en_US.UTF-8', 09:00:35 INFO - 'LANGUAGE': 'en_US:en', 09:00:35 INFO - 'LOGNAME': 'cltbld', 09:00:35 INFO - 'MAIL': '/var/mail/cltbld', 09:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:35 INFO - 'MOZ_NO_REMOTE': '1', 09:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:35 INFO - 'NO_EM_RESTART': '1', 09:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:35 INFO - 'PWD': '/builds/slave/test', 09:00:35 INFO - 'SHELL': '/bin/bash', 09:00:35 INFO - 'SHLVL': '1', 09:00:35 INFO - 'TERM': 'linux', 09:00:35 INFO - 'TMOUT': '86400', 09:00:35 INFO - 'USER': 'cltbld', 09:00:35 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:35 INFO - '_': '/tools/buildbot/bin/python'} 09:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:35 INFO - Cleaning up... 09:00:35 INFO - Return code: 0 09:00:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', '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:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:00:35 INFO - Using env: {'DISPLAY': ':0', 09:00:35 INFO - 'HOME': '/home/cltbld', 09:00:35 INFO - 'LANG': 'en_US.UTF-8', 09:00:35 INFO - 'LANGUAGE': 'en_US:en', 09:00:35 INFO - 'LOGNAME': 'cltbld', 09:00:35 INFO - 'MAIL': '/var/mail/cltbld', 09:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:35 INFO - 'MOZ_NO_REMOTE': '1', 09:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:35 INFO - 'NO_EM_RESTART': '1', 09:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:35 INFO - 'PWD': '/builds/slave/test', 09:00:35 INFO - 'SHELL': '/bin/bash', 09:00:35 INFO - 'SHLVL': '1', 09:00:35 INFO - 'TERM': 'linux', 09:00:35 INFO - 'TMOUT': '86400', 09:00:35 INFO - 'USER': 'cltbld', 09:00:35 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:35 INFO - '_': '/tools/buildbot/bin/python'} 09:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:35 INFO - Cleaning up... 09:00:35 INFO - Return code: 0 09:00:35 INFO - Running post-action listener: _resource_record_post_action 09:00:35 INFO - Running post-action listener: _start_resource_monitoring 09:00:35 INFO - Starting resource monitoring. 09:00:35 INFO - [mozharness: 2017-03-15 16:00:35.926448Z] Finished create-virtualenv step (success) 09:00:35 INFO - [mozharness: 2017-03-15 16:00:35.927034Z] Running install step. 09:00:35 INFO - Running pre-action listener: _resource_record_pre_action 09:00:35 INFO - Running main action method: install 09:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:36 INFO - Reading from file tmpfile_stdout 09:00:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:36 INFO - Reading from file tmpfile_stdout 09:00:36 INFO - Output received: 09:00:36 INFO - Usage: mozinstall [options] installer 09:00:36 INFO - Options: 09:00:36 INFO - -h, --help show this help message and exit 09:00:36 INFO - -d DEST, --destination=DEST 09:00:36 INFO - Directory to install application into. [default: 09:00:36 INFO - "/builds/slave/test"] 09:00:36 INFO - --app=APP Application being installed. [default: firefox] 09:00:36 INFO - mkdir: /builds/slave/test/build/application 09:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:00:52 INFO - Reading from file tmpfile_stdout 09:00:52 INFO - Output received: 09:00:52 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:52 INFO - Running post-action listener: _resource_record_post_action 09:00:52 INFO - [mozharness: 2017-03-15 16:00:52.910062Z] Finished install step (success) 09:00:52 INFO - [mozharness: 2017-03-15 16:00:52.910210Z] Running run-tests step. 09:00:52 INFO - Running pre-action listener: _resource_record_pre_action 09:00:52 INFO - Running main action method: run_tests 09:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:52 INFO - Python 2.7.3 09:00:52 INFO - Return code: 0 09:00:52 INFO - grabbing minidump binary from tooltool 09:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:52 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 0xf6e400>, '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 0xf6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6f840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:52 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:00:52 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:00:52 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:00:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:52 INFO - Return code: 0 09:00:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:52 INFO - ENV: RUST_BACKTRACE is now 1 09:00:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:52 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/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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:00:52 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/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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:00:52 INFO - Using env: {'DISPLAY': ':0', 09:00:52 INFO - 'HOME': '/home/cltbld', 09:00:52 INFO - 'LANG': 'en_US.UTF-8', 09:00:52 INFO - 'LANGUAGE': 'en_US:en', 09:00:52 INFO - 'LOGNAME': 'cltbld', 09:00:52 INFO - 'MAIL': '/var/mail/cltbld', 09:00:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:52 INFO - 'MOZ_NO_REMOTE': '1', 09:00:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:52 INFO - 'NO_EM_RESTART': '1', 09:00:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:52 INFO - 'PWD': '/builds/slave/test', 09:00:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:52 INFO - 'RUST_BACKTRACE': '1', 09:00:52 INFO - 'SHELL': '/bin/bash', 09:00:52 INFO - 'SHLVL': '1', 09:00:52 INFO - 'TERM': 'linux', 09:00:52 INFO - 'TMOUT': '86400', 09:00:52 INFO - 'USER': 'cltbld', 09:00:52 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304', 09:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:52 INFO - '_': '/tools/buildbot/bin/python'} 09:00:52 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/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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:00:53 INFO - mozversion application_buildid: 20170315151240 09:00:53 INFO - mozversion application_changeset: 8c89d1991786625a64d868798281610872a2bc26 09:00:53 INFO - mozversion application_display_name: Nightly 09:00:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:53 INFO - mozversion application_name: Firefox 09:00:53 INFO - mozversion application_remotingname: firefox 09:00:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:00:53 INFO - mozversion application_vendor: Mozilla 09:00:53 INFO - mozversion application_version: 55.0a1 09:00:53 INFO - mozversion platform_buildid: 20170315151240 09:00:53 INFO - mozversion platform_changeset: 8c89d1991786625a64d868798281610872a2bc26 09:00:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:00:53 INFO - mozversion platform_version: 55.0a1 09:00:53 INFO - using testdate: 1489593653 09:00:53 INFO - actual date: 1489593653 09:00:53 INFO - starting webserver on 'localhost:45854' 09:00:53 INFO - SUITE-START | Running 2 tests 09:00:53 INFO - TEST-START | dromaeo_css 09:00:53 INFO - operating with platform_type : linux_ 09:00:53 INFO - Initialising browser for dromaeo_css test... 09:00:53 INFO - TEST-INFO | started process 15724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTmS8YY/profile http://localhost:45854/getInfo.html) 09:00:57 INFO - PID 15724 | __metrics Screen width/height:1600/1200 09:00:57 INFO - PID 15724 | colorDepth:24 09:00:57 INFO - PID 15724 | Browser inner width/height: 1024/697 09:00:57 INFO - PID 15724 | __metrics 09:00:58 INFO - PID 15724 | 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:00:58 INFO - PID 15724 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:00:59 INFO - TEST-INFO | 15724: exit 0 09:00:59 INFO - Browser initialized. 09:00:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:59 INFO - TEST-INFO | started process 15815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTmS8YY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:10 INFO - PID 15815 | RSS: Main: 144744448 09:01:10 INFO - PID 15815 | 09:04:13 INFO - PID 15815 | Cycle 1(1): loaded http://localhost:45854/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45854/tests/dromaeo/cssquery-ext.html) 09:04:14 INFO - PID 15815 | RSS: Main: 279089152 09:04:14 INFO - PID 15815 | 09:06:01 INFO - PID 15815 | ************************* 09:06:01 INFO - PID 15815 | A coding exception was thrown and uncaught in a Task. 09:06:01 INFO - PID 15815 | 09:06:01 ERROR - PID 15815 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:06:01 INFO - PID 15815 | Full stack: 09:06:01 INFO - PID 15815 | ************************* 09:07:16 INFO - PID 15815 | Cycle 1(1): loaded http://localhost:45854/tests/dromaeo/cssquery-ext.html (next: http://localhost:45854/tests/dromaeo/cssquery-jquery.html) 09:07:17 INFO - PID 15815 | RSS: Main: 243744768 09:07:17 INFO - PID 15815 | 09:10:19 INFO - PID 15815 | Cycle 1(1): loaded http://localhost:45854/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45854/tests/dromaeo/cssquery-mootools.html) 09:10:19 INFO - PID 15815 | RSS: Main: 244154368 09:10:19 INFO - PID 15815 | 09:13:21 INFO - PID 15815 | Cycle 1(1): loaded http://localhost:45854/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45854/tests/dromaeo/cssquery-prototype.html) 09:13:22 INFO - PID 15815 | RSS: Main: 206983168 09:13:22 INFO - PID 15815 | 09:16:25 INFO - PID 15815 | Cycle 1(1): loaded http://localhost:45854/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45854/tests/dromaeo/cssquery-yui.html) 09:16:25 INFO - PID 15815 | RSS: Main: 186179584 09:16:25 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | Cycle 1(1): loaded http://localhost:45854/tests/dromaeo/cssquery-yui.html (next: http://localhost:45854/tests/dromaeo/cssquery-dojo.html) 09:19:28 INFO - PID 15815 | RSS: Main: 277291008 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | __start_tp_report 09:19:28 INFO - PID 15815 | _x_x_mozilla_page_load 09:19:28 INFO - PID 15815 | _x_x_mozilla_page_load_details 09:19:28 INFO - PID 15815 | |i|pagename|runs| 09:19:28 INFO - PID 15815 | |0;dojo.html;3207.502837060256;3224.6452890182077;3256.609206895173;3261.445554255777;3280.245543524989;5407.540359069484;5431.484009019142;5457.045017122003;5490.121580547113;5490.450954904512;5438.809641662542;5449.918251226235;5453.427390124039;5459.836204913859;5511.58663100267;4888.315635810989;4925.261210818388;4947.529984651453;4956.727379994099;4960.875978100558;4928.408590969088;4953.281774142747;4965.577925876305;4982.651214414992;5011.548960593545;4784.091022705709;4946;4960.032793605248;5010.498950104997;5026.195808670614;4866.3673722416215;4878.341423907798;4911.68074075184;4912.70523768575;4913.975430122862;4880.365552478191;4887.437944636376;4933.038057578774;4940.876478088041;4943.555080042814;5373.81191658294;5375.354957405137;5398.2982212312545;5414.133738601844;5434.809781657663;5831.300243970751;5854.297484301911;5857.179994800731;5862.706864656793;5906.675132867734;5693.501021133231;5705.885882282372;5720.942790572082;5748.7987920423;5749.223854779635;5467.535259502947;5490.368607610096;5490.752916118784;5491.588130890199;5492.176173573916;5891.764329426775;5967.343592204854;5996.220491336098;5999.310079340931;6024.789132380346;5685.488306052475;5690.630109042899;5691.544676425876;5742.425757424224;5744.080947048452;5738.081906894877;5794.188813566104;5800.941990580124;5842.444967728059;5878.2064421302575;5848.941510584839;5850.3564607893095;5853.443922827325;5855.970720146457;5871.4128587141795;5739.713014349266;5794.536437085023;5804.9419505805245;5827.912581311284;5845.88308233833;4536.047430039728;4538.72767633943;4554.4990051094355;4556.157110934488;4561.338605902126;4035.6771458283265;4044.2315959967514;4050.25070361984;4070.063885306396;4081.6326530612387;5421.783128674809;5469.179623056494;5535.667859928417;5552.3892371839065;5554.333479982428;5856.502197313276;5887.646741195542;5901;5928.140419639129;5933.554983376264;5501.4223506532035;5506.972465137648;5515.310586176728;5532;5532.363778165482;4947.529984651453;5018.774155163026;5021.322121513622;5024.57291130258;5071.847844564669;6020.397960204032;6045.1234570987845;6055.1522786810765;6106.297775755667;6107.84730381744;5859.58982871212;5889.411058894077;5913.46778789911;5931.169636251015;5931.258592675916;6113.877722445437;6118.969405152946;6122.204113465399;6122.234720659918;6123.938760612336;5677.5457963362005;5740.540756739534;5748.798792042258;5810.5351571875;5828.329742079715;5899.528037756883;5907.17299578068;5907.261592300963;5907.5273978080795;5938.346781853907;5526.336839579276;5758.71206439668;5763.6541807491685;5765.7117144141785;5767.307923049261;5690.886182276414;5698.971505142447;5708.4862362387585;5755.71221438918;5769.105788602773;5637.9718101409235;5677.261955945701;5682.857928551819;5687.431256874445;5714.514266287412;5696.373398926197;5703.287089113861;5746.367899531132;5757.625754325955;5759.94240057594;5524.751386187711;5530.364008139116;5587.273654424899;5591.776328946959;5592.356878958809 09:19:28 INFO - PID 15815 | |1;ext.html;681.7613835157696;744.5755919126099;765.03605457124;815.0463957170114;828.2669837194081;28642.28394290144;36958.445623315674;37646.8117659412;37653.81173094131;37737.24525509488;42457.36313955286;42862.57137428625;42897.57102428983;42920;42989;6902.482313826458;7162.785116446515;8455.957720211405;8595.44129631572;8596.441231319945;9886.802263954716;9959.90040099597;10218;10219.335743176707;10227.539760710786;10234.948825255864;10409.167266618653;10460.4769761512;10465.31975421599;10466;10327.586896524168;10415.68752937413;10416.739581510446;10523.78952420955;10558.419286939215;9476.857847132298;9573.760655983586;9628.074385122933;9850.014998500164;9883.752906177404;39459.60540394588;39547.20905581901;39698;39732.20535589272;39798.60201397978;3798.3542797724454;3818.179091495314;3832.9041773955596;3841.2701586698718;3899.473571067898;28521.286967825763;28544;28591;29414.558781618292;29550;4163.625273725379;4197.580241975808;4211.978940105279;4295.978520107379;4431.202383570956;209572;209708;210011;210818.94590527102;211233;50048.49951500511;50226.246606300934;50474.24288635673;50500.74749626228;50528;52212.47787522152;52598.737006314725;52726;52768.472315277126;52778.20832687513;14958.177300248373;15335.386584536494;15352.769708454382;15365;15368.542078032353;10190.592376304865;10435.43468479719;12320.383980801103;12403.193792403645;12409.565665201675;12799.232046077264;12959;13028.513278927356;13164.934175329254;13552.390142443614;5255.582432081209;5339.145736682112;5342.9465705342445;5365.946340536622;5492.642978206404;915.9175674189355;934.6728644974205;945.9763505912407;948.4688574398222;949.781550243434;24467;25995.220143395727;26267.474650506883;26393.472130557664;26445.206643800717;990.2474119669104;990.4898977026837;998.9700308990742;1010.8938561451089;1022.5807418958192;13089.738205235843;13622.931885340708;13990.370433330525;14024.859751402559;14066.226357550433;290886;291322;291860.3023516227;292074;293660;53798.731006344715;53844.461555384725;53900.73049634805;53949.1907621386;53990;1361.8229630148016;1376.2361889151616;1399.3283224052511;1407.4933024111108;1414.5402744108205;23916.76083239215;24125.924491001977;24903.875480623206;24998.500029998933;25044.62433063542;22185.334439966828;22297.331080067623;22377;22388.440288992904;22408.215712449986;265668;265732;266032;266663;266919;25355.492890142465;28092.85953570301;32858.34283314273;32941.835290824354;33024.834875825465;35827.74602888886;36060.4590931131;36541.26917461689;36706.4494032595;36733.08167295732;30885;30901.69098308988;31182.532262016543;31580;32319.35361292808;30225.395492089596;30366.544501832934;30497.54253686152;30983.69016309808;31822.727090916105;35348.29303413866;36075.639243608275;36089.81955090208;36154.8192259037;36182.27635447329;27586.172414827586;30753.078741136957;30796.53805192879;30827.229319267197;30955.851520536875;23828.761712382653;24042.278731638078;24304.392390190387;24590.754092458847;24692.876535617208 09:19:28 INFO - PID 15815 | |2;jquery.html;415.78379242793767;431.01298027516975;432.3795353667487;443.09608398866317;454.14393867559653;18411.53971150722;19585.314513992013;20413;21568.813715245655;21684.132634694593;19874.602507949832;20248.49378765528;20863.37409877702;20896.791032089674;21055.57888842226;6218.440340369366;6681.966590167067;6683.53215274931;6684.632345221011;6689.565178263421;6378.649174295412;6497.382748638872;6557.475401967855;6580.572262802926;6600.108985286973;6385.968070159666;6597.439217666504;6606.108175396308;6608.041833934077;6608.834779130536;6478.093066970628;6571.7371305147735;6587.835304117388;6589.703463344137;6590.1432813734145;6418.8716225675225;6428.132202152697;6982.581045137308;7263.164736055315;7318.085239345082;23009.88495057514;23358.76641233583;24421;24499;24642.137525186532;2788.888444462219;2821.6331876856084;2853.9714602853915;2873.8563071846534;2874.928126796826;15257.694846103015;15555.30001149951;15762.763558546516;16141.91929040359;16406.04306911147;2126.8936553172434;2136.6688163334707;2170.1753333733236;2194.2758889566403;2224.9110035598555;84429.57785211035;103543.48228258886;103586.99999999924;104223;105100;17243.3964811231;17820.376286829898;18097.819021809875;18168.364107256184;18171.72742408865;33424.83287583546;33506.66493335084;33616.15959601029;33689;33726.66273337285;9608.807823843485;9954.850677239847;10039.598416063422;10054.693274205469;10325.277230593787;8198.754037378889;8207.753767386868;8439.324854011787;8525.488470691778;8554.35842311829;9436.575354109082;9448.57481413338;9769.267304952156;9909.950450247703;9970.302078854556;3676.852925882935;3808.8383043171543;3868.3172819823467;3980.6019398060544;3999.8800035998966;872.7381785464336;900.3337530227584;900.7883147460342;912.9777542742856;925.7870689741397;21292.467688307606;22398.77601223967;22521;22563.323100307018;22623.321300361014;902.201551626815;905.0994260710635;907.9546022698838;912.9224015958717;932.2122806228726;12198.57304994321;12268.263904165779;12482.687932801753;12491.937540312421;12514.124011319302;600923;620668;631482;641604;642042;85374.57312713396;86714;91964;106405;108749;1179.9469023893946;1219.9329036902877;1262.665393670678;1272.1413046193782;1273.8216649669055;19491.317803876795;19517.70723439121;20411.693824592345;20442.69335959932;20444.488887777923;47162;47215.527844722485;48879;49105.50894491009;49465.50534494609;57948.4205157943;60787.69606151941;69830.65084674544;70823.64588177027;71148.64425677838;12411.937940310241;13142;13169.604911852659;13223.404946777226;13363.198208107544;14396.85603143955;14418.567442976728;14471.78292325637;14525.782113268098;14530.927345363205;9844.655437059668;12074.335911524598;12377.814332785196;12628;12989.610311690287;12254.877451225373;12508.374581270718;12732.554360597334;12833.42249598789;12944.15862968904;11836.349000805038;12321.383930803244;12367.443465044274;12872.678183045498;13064.150830196008;12967.286799226133;13056.477740890257;13070.281134537692;13159.407826647632;13514.324283785574;11888.881111189123;12507.187032842836;12568.560100396442;12610.83841834884;12625.684357891127 09:19:28 INFO - PID 15815 | |3;mootools.html;1486.9405223791048;1509.433962264151;1513.1223890143717;1522.1704171226677;1597.7603359496077;10124.291299609022;10552.947235263813;10566.630167944124;10568.78862422751;10570.101541368973;10299.794004119913;10511.894881051207;10748.892511074888;10792.73018174546;10810.459477026137;2179.7166368372127;2239.361781892161;2239.9888000560054;2240.607893618618;2245.528439027798;2170.739511258651;2208.3816531371162;2210.2153735423935;2211.7677643847405;2212.126210146991;2162.2540223622823;2180.7819218078225;2188.245055455865;2192.506685995646;2200.90095945682;2102.8843413612244;2123.9469013274716;2141.668041453577;2142.73215848019;2145.452909508081;2112.324056301984;2171.6416791229426;2178.096090122597;2179.52050548879;2182.945426364338;10476.371417714885;10538.789224215549;10570.312929659547;10570.682879513626;10581.312214706097;5166.276721259027;5390.6226564140525;5392.272043274186;5396.244525766396;5397.8380648580605;3085.213270616001;3278.50822376643;3391.372596069735;3394.8472318745717;3404.3872103021445;1251.330538162079;1286.8906142977858;1324.7681655710212;1403.4666826605824;1407.1908652524737;12767.93616031914;13771.311434428339;13938.58184254474;14846.70306593873;15077.170755608437;4306.978465107654;4415.4259946206785;4524.072565124142;4594.517575654508;4599.563041511051;13209.669758256121;13211.273379964003;13213.537526186537;13227.338633068308;13232;4584.770761461914;4713.858584242478;5343.679379237258;5465.9180112298345;5520.641158324696;4908.165611845923;4968.478309777493;4981.32752078472;4982.202847544376;4982.750862456863;3781.149241420658;3793.203427280247;3905.453236546886;3925.509311336083;3941.0935484838074;1038.5170895533613;1051.7948999944938;1072.2601405030505;1077.224398433127;1083.6748975307376;989.5992123190119;990.9702708918744;1012.2458768217725;1019.495349801838;1033.3748082410184;6657.933420665829;6860.142482189726;6881.277465866112;6907.481938854606;7043.894341584881;1014.4217795856291;1016.872890888639;1039.6621098143135;1060.6977011551671;1069.4973362519604;4403;4440.644748420119;4464;4484.192845287879;4516.706414083074;12939.482420703067;13408.932955335162;13717.931410342884;13946.093503922213;14009.369578368998;13590.184588924696;13645.59063228085;13808.585742427744;13822.585322440344;13839.308034598229;8296.377771667148;9846.556904939152;10091.596336146471;10091.848622270672;10103.393796372242;6040.7583696651645;6615.272320044887;6625.668716564249;6666;6765.830854228684;11121.388323642277;11322.60370887015;11405.885941140483;11522.078233741113;11756.58851940178;11649.534018639159;11670.824937625772;11748.765024699629;11895.524179033087;11987;8350.331973442231;8484.787880303042;8562.957185214034;8571.742847714579;8573.74278771638;9747;9854.162396196403;9877.357971731815;9929.156021738234;10003.699889003341;8358.83282334362;8424.115467875785;8527.701530446404;8790.956045219733;8793.956030220064;8710.085441028854;8786.077461866413;8820.64717411322;8847.11528847136;8915.465072095694;7714.498557593738;7815.921840781747;7861.921380786119;7877.409194310564;7880.684772609261;5786.797462088975;5893.145493903446;5914.911276330772;6059.394060593905;6065.727042283196;5967.6121052131475;5980.5215582754145;6021.87956240864;6099.969500152471;6112.083187521729 09:19:28 INFO - PID 15815 | |4;prototype.html;394.9824424886847;403.3566461493918;410.6181251436164;414.7345698752797;422.3263894088928;5587.357453892803;5709.857253568658;5720.770034442594;5727.284089488814;5853.648781073139;5585.162225666142;5594.58040646951;5622.718864056801;5623.915641265385;5643.181738647893;4678.812847486097;4684.695494792828;4691.272852707836;4697.976510117444;4705.1530724469585;4487.416635837353;4665.83669571565;4687.250039993602;4691.483936766953;4702.90594188116;4636.397268355109;4642.07158568286;4660.300954856764;4660.580547750702;4662.114198302312;4651.000034992493;4651.255799072149;4658.114958157939;4667.696599721007;4679.900223447487;4262.488501379823;4355.281378572532;4360.236958532244;4380.518143004267;4389.770864158041;5472.288602481692;5472.480114389126;5495.670259784426;5509.118541033456;5521.3926468088475;5489.505944464978;5495.8351249462185;5496.945030549683;5507.614466987313;5510.586705997066;5807.738651760681;5953.702314884238;5986.28164620244;5990.880182396328;5999.520038396918;4851.660383773137;4854.805807767685;4859.708417494961;4871.415430148405;4872.244802055651;5925.970370148165;6078.966575682145;6140;6188.381161883821;6290.433860952537;4988.35151430319;5001.8499445016705;5018.920931999638;5021.974890125526;5034.11902916988;5058.595312375001;5087.949120508748;5101.897962040738;5111.923321150186;5120.743962801845;5214.165733482625;5414.160805075219;5467.644603100865;5471.890562188813;5474.206240095244;5944.256967879015;5977.133315669291;5982.880342393215;6002.339742628307;6014.639121652627;1774.4233124234675;1789.5526118470382;1791.4536066499581;1795.6408718256139;1860.7674040744907;1561.2505997121445;1567.5454118305563;1588.4202266172763;1598.5204438668352;1613.475620423367;5721.685307308138;5745.683987380734;5771.932192544393;5780.566457515619;5780.942190578125;6174.289956654952;6177.907331390033;6196.411340922605;6199.256089269316;6203.596766210182;5559.305086864142;5720.11338242573;5736.225609542742;5753.88492230153;5760.884782304331;3231.644519102897;3264.477683570665;3264.983675081657;3267.4118658641196;3286.35448463984;6035.215421995113;6350.333215012357;6357.904631430532;6382.7130276677435;6398.392152745575;6305.905411418832;6312.810615681537;6314.463270621956;6351.809445716636;6354.777582784581;6290.9056364155485;6403.327650596713;6403.807885763342;6418.454431373292;6419.005054216604;6146.108814222002;6150.1082343059115;6160.414760597736;6163.753449861955;6185.876282474415;5807;5965.73154208053;6073.605215660968;6112.419320164578;6113.663748493876;6201.720922558586;6261.718222680081;6330.746770129143;6362.554621176473;6366.9681651591445;5009.674371165863;5038.042771873333;5053.873653158554;5062.670926389773;5076.568491678248;6046.84882877914;6078;6142.57002009855;6153.84615384619;6162.661053642093;5958.880822383441;5984.910226346521;5987.191729116514;5988.790392336247;6012.098185272244;5813.186153938367;5973.461278956513;6018.759249629966;6024.548158888013;6038.184845045863;5939.3466718661775;5988.610740301867;5993.820185394445;5997.280326360865;6004;5881.735321910439;5998.970005149947;6006.249218847644;6011.489023433057;6023.1868697726995;5838.474537291494;5843.211166492469;5856.267966504187;5863.442972917736;5889.58772885911 09:19:28 INFO - PID 15815 | |5;yui.html;686.3719696477723;733.8715724748167;837.51842690453;848.9830203395936;859.4542465534386;13447.529336473226;13718.245496497688;13739.862601373983;13761;13873.722525549496;14552.417903283882;14619.780703289432;14641.926790366033;14646.41414343425;14731.263436828169;806.9475484093515;830.667732906836;831.9625616847226;839.1147339556766;839.6263662670115;831.9500829950192;834.1283358889945;834.5576844272546;834.6119054639591;835.0563862834988;850.6342272822684;851.4763420496412;851.9616617252208;853.8292341531687;856.3577317012241;842.3850589069982;848.1518481518482;849.7705619482767;849.8512760266929;852.8038551133212;620.5842085802523;621.6767281013892;621.9968900155516;622.483338828771;622.7135517661881;15714.44999425026;15839.445619403381;16086.75869861953;16694.66610667792;16775.748363774554;15251.856110791625;15481.612959675986;15511.146886921215;15814;16106.677866442606;12232.510699572007;12616.243025418504;12751.298678572675;12917.612471625866;12923.547675831302;3894.3768996960634;3937.0944682723134;3940.566537680853;4008.518977722663;4062.7765472898977;63130.68434657843;73093;86588.56705716494;86979;88958.5552072242;11897.881021189678;11901.166918315808;11913.04695624348;11960.820587691192;12227.449764760608;23958.52082958366;24127.396815079413;24239.03043878225;24317.635235471484;24406.023759049793;8367.28878045373;8398.370122240856;8449.197326253996;8522.232999030117;8725.258353040063;4317.0934103838545;4420.624246939046;4425.690201685916;4430.867073987785;4481.75350355727;3160.9683903161135;3188.505781603855;3199.232184275757;3200.6639302873327;3245.8214798185854;549.6839317392515;551.737924485866;553.0818840724459;556.1962963517678;564.4327450911808;610.2585358789092;620.3486339343671;626.2078470734524;634.1660716158237;641.4066988036049;9898.257630677585;10054.547545360476;10251.84622230667;10256.230782691327;10456.63401780934;739.0909181706423;759.6880029714002;763.522798251093;774.3263360876074;778.5523324088558;4765.8093676252565;5171.4311425743135;5181.093308670967;5185.766640501187;5240;65511;78499.60750196327;80918.19081809225;83957.58021209855;86433.56783216044;69340.65329673319;69641.65179174072;70766;73504.63247683727;74500.25499745042;63929.36070639234;63994;64799.352006479334;64824.67587662031;64864.675676621315;10105.372343873798;10294.330868493524;10323.94838025805;10400;10427.582896684047;44972.15362002919;45279.54720452753;45418;45697.77151114356;47601.761991191204;53004.46995529995;53351;53512.73243633757;54767.45232547623;54996.45003549913;12378.319192444502;12732.74534509286;12980.610581682564;13036.739265214832;13200.273984930922;13465.865341346462;13512.557481428794;13562.93218533901;13669;13925;11000.174986876176;11359.48882300282;11439.714007149887;11453.770924581295;11945.462454189408;11094.944525277322;11232.101431885592;11433.771324573296;11934.28394296345;11936.52253909834;12619.621411357673;12735.363231838557;13014.21914685122;13158.342082895626;13696.383662735001;11202;11262.324260544394;11280.379579123228;11554.826677600011;11834.349110798988;9354.251659867059;9938.204943604349;9946.751331216778;9981.500924953578;10035.147012503727 09:19:28 INFO - PID 15815 | __end_tp_report 09:19:28 INFO - PID 15815 | __start_cc_report 09:19:28 INFO - PID 15815 | _x_x_mozilla_cycle_collect,1301 09:19:28 INFO - PID 15815 | __end_cc_report 09:19:28 INFO - PID 15815 | __startTimestamp1489594768917__endTimestamp 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | ------- Summary: start ------- 09:19:28 INFO - PID 15815 | Number of tests: 6 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | [#0] dojo.html Cycles:165 Average:5429.93 Median:5677.26 stddev:605.72 (10.7%) stddev-sans-first:581.95 09:19:28 INFO - PID 15815 | Values: 3207.5 3224.6 3256.6 3261.4 3280.2 5407.5 5431.5 5457.0 5490.1 5490.5 5438.8 5449.9 5453.4 5459.8 5511.6 4888.3 4925.3 4947.5 4956.7 4960.9 4928.4 4953.3 4965.6 4982.7 5011.5 4784.1 4946.0 4960.0 5010.5 5026.2 4866.4 4878.3 4911.7 4912.7 4914.0 4880.4 4887.4 4933.0 4940.9 4943.6 5373.8 5375.4 5398.3 5414.1 5434.8 5831.3 5854.3 5857.2 5862.7 5906.7 5693.5 5705.9 5720.9 5748.8 5749.2 5467.5 5490.4 5490.8 5491.6 5492.2 5891.8 5967.3 5996.2 5999.3 6024.8 5685.5 5690.6 5691.5 5742.4 5744.1 5738.1 5794.2 5800.9 5842.4 5878.2 5848.9 5850.4 5853.4 5856.0 5871.4 5739.7 5794.5 5804.9 5827.9 5845.9 4536.0 4538.7 4554.5 4556.2 4561.3 4035.7 4044.2 4050.3 4070.1 4081.6 5421.8 5469.2 5535.7 5552.4 5554.3 5856.5 5887.6 5901.0 5928.1 5933.6 5501.4 5507.0 5515.3 5532.0 5532.4 4947.5 5018.8 5021.3 5024.6 5071.8 6020.4 6045.1 6055.2 6106.3 6107.8 5859.6 5889.4 5913.5 5931.2 5931.3 6113.9 6119.0 6122.2 6122.2 6123.9 5677.5 5740.5 5748.8 5810.5 5828.3 5899.5 5907.2 5907.3 5907.5 5938.3 5526.3 5758.7 5763.7 5765.7 5767.3 5690.9 5699.0 5708.5 5755.7 5769.1 5638.0 5677.3 5682.9 5687.4 5714.5 5696.4 5703.3 5746.4 5757.6 5759.9 5524.8 5530.4 5587.3 5591.8 5592.4 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | [#1] ext.html Cycles:180 Average:41236.76 Median:30893.35 stddev:67451.89 (218.3%) stddev-sans-first:67572.38 09:19:28 INFO - PID 15815 | Values: 681.8 744.6 765.0 815.0 828.3 28642.3 36958.4 37646.8 37653.8 37737.2 42457.4 42862.6 42897.6 42920.0 42989.0 6902.5 7162.8 8456.0 8595.4 8596.4 9886.8 9959.9 10218.0 10219.3 10227.5 10234.9 10409.2 10460.5 10465.3 10466.0 10327.6 10415.7 10416.7 10523.8 10558.4 9476.9 9573.8 9628.1 9850.0 9883.8 39459.6 39547.2 39698.0 39732.2 39798.6 3798.4 3818.2 3832.9 3841.3 3899.5 28521.3 28544.0 28591.0 29414.6 29550.0 4163.6 4197.6 4212.0 4296.0 4431.2 209572.0 209708.0 210011.0 210818.9 211233.0 50048.5 50226.2 50474.2 50500.7 50528.0 52212.5 52598.7 52726.0 52768.5 52778.2 14958.2 15335.4 15352.8 15365.0 15368.5 10190.6 10435.4 12320.4 12403.2 12409.6 12799.2 12959.0 13028.5 13164.9 13552.4 5255.6 5339.1 5342.9 5365.9 5492.6 915.9 934.7 946.0 948.5 949.8 24467.0 25995.2 26267.5 26393.5 26445.2 990.2 990.5 999.0 1010.9 1022.6 13089.7 13622.9 13990.4 14024.9 14066.2 290886.0 291322.0 291860.3 292074.0 293660.0 53798.7 53844.5 53900.7 53949.2 53990.0 1361.8 1376.2 1399.3 1407.5 1414.5 23916.8 24125.9 24903.9 24998.5 25044.6 22185.3 22297.3 22377.0 22388.4 22408.2 265668.0 265732.0 266032.0 266663.0 266919.0 25355.5 28092.9 32858.3 32941.8 33024.8 35827.7 36060.5 36541.3 36706.4 36733.1 30885.0 30901.7 31182.5 31580.0 32319.4 30225.4 30366.5 30497.5 30983.7 31822.7 35348.3 36075.6 36089.8 36154.8 36182.3 27586.2 30753.1 30796.5 30827.2 30955.9 23828.8 24042.3 24304.4 24590.8 24692.9 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | [#2] jquery.html Cycles:180 Average:35795.23 Median:24460.00 stddev:102978.47 (421.0%) stddev-sans-first:103233.08 09:19:28 INFO - PID 15815 | Values: 415.8 431.0 432.4 443.1 454.1 18411.5 19585.3 20413.0 21568.8 21684.1 19874.6 20248.5 20863.4 20896.8 21055.6 6218.4 6682.0 6683.5 6684.6 6689.6 6378.6 6497.4 6557.5 6580.6 6600.1 6386.0 6597.4 6606.1 6608.0 6608.8 6478.1 6571.7 6587.8 6589.7 6590.1 6418.9 6428.1 6982.6 7263.2 7318.1 23009.9 23358.8 24421.0 24499.0 24642.1 2788.9 2821.6 2854.0 2873.9 2874.9 15257.7 15555.3 15762.8 16141.9 16406.0 2126.9 2136.7 2170.2 2194.3 2224.9 84429.6 103543.5 103587.0 104223.0 105100.0 17243.4 17820.4 18097.8 18168.4 18171.7 33424.8 33506.7 33616.2 33689.0 33726.7 9608.8 9954.9 10039.6 10054.7 10325.3 8198.8 8207.8 8439.3 8525.5 8554.4 9436.6 9448.6 9769.3 9910.0 9970.3 3676.9 3808.8 3868.3 3980.6 3999.9 872.7 900.3 900.8 913.0 925.8 21292.5 22398.8 22521.0 22563.3 22623.3 902.2 905.1 908.0 912.9 932.2 12198.6 12268.3 12482.7 12491.9 12514.1 600923.0 620668.0 631482.0 641604.0 642042.0 85374.6 86714.0 91964.0 106405.0 108749.0 1179.9 1219.9 1262.7 1272.1 1273.8 19491.3 19517.7 20411.7 20442.7 20444.5 47162.0 47215.5 48879.0 49105.5 49465.5 57948.4 60787.7 69830.7 70823.6 71148.6 12411.9 13142.0 13169.6 13223.4 13363.2 14396.9 14418.6 14471.8 14525.8 14530.9 9844.7 12074.3 12377.8 12628.0 12989.6 12254.9 12508.4 12732.6 12833.4 12944.2 11836.3 12321.4 12367.4 12872.7 13064.2 12967.3 13056.5 13070.3 13159.4 13514.3 11888.9 12507.2 12568.6 12610.8 12625.7 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | [#3] mootools.html Cycles:180 Average:6574.15 Median:2210.99 stddev:4132.61 (186.9%) stddev-sans-first:4126.53 09:19:28 INFO - PID 15815 | Values: 1486.9 1509.4 1513.1 1522.2 1597.8 10124.3 10552.9 10566.6 10568.8 10570.1 10299.8 10511.9 10748.9 10792.7 10810.5 2179.7 2239.4 2240.0 2240.6 2245.5 2170.7 2208.4 2210.2 2211.8 2212.1 2162.3 2180.8 2188.2 2192.5 2200.9 2102.9 2123.9 2141.7 2142.7 2145.5 2112.3 2171.6 2178.1 2179.5 2182.9 10476.4 10538.8 10570.3 10570.7 10581.3 5166.3 5390.6 5392.3 5396.2 5397.8 3085.2 3278.5 3391.4 3394.8 3404.4 1251.3 1286.9 1324.8 1403.5 1407.2 12767.9 13771.3 13938.6 14846.7 15077.2 4307.0 4415.4 4524.1 4594.5 4599.6 13209.7 13211.3 13213.5 13227.3 13232.0 4584.8 4713.9 5343.7 5465.9 5520.6 4908.2 4968.5 4981.3 4982.2 4982.8 3781.1 3793.2 3905.5 3925.5 3941.1 1038.5 1051.8 1072.3 1077.2 1083.7 989.6 991.0 1012.2 1019.5 1033.4 6657.9 6860.1 6881.3 6907.5 7043.9 1014.4 1016.9 1039.7 1060.7 1069.5 4403.0 4440.6 4464.0 4484.2 4516.7 12939.5 13408.9 13717.9 13946.1 14009.4 13590.2 13645.6 13808.6 13822.6 13839.3 8296.4 9846.6 10091.6 10091.8 10103.4 6040.8 6615.3 6625.7 6666.0 6765.8 11121.4 11322.6 11405.9 11522.1 11756.6 11649.5 11670.8 11748.8 11895.5 11987.0 8350.3 8484.8 8563.0 8571.7 8573.7 9747.0 9854.2 9877.4 9929.2 10003.7 8358.8 8424.1 8527.7 8791.0 8794.0 8710.1 8786.1 8820.6 8847.1 8915.5 7714.5 7815.9 7861.9 7877.4 7880.7 5786.8 5893.1 5914.9 6059.4 6065.7 5967.6 5980.5 6021.9 6100.0 6112.1 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | [#4] prototype.html Cycles:180 Average:5192.66 Median:5724.48 stddev:1379.72 (24.1%) stddev-sans-first:1335.77 09:19:28 INFO - PID 15815 | Values: 395.0 403.4 410.6 414.7 422.3 5587.4 5709.9 5720.8 5727.3 5853.6 5585.2 5594.6 5622.7 5623.9 5643.2 4678.8 4684.7 4691.3 4698.0 4705.2 4487.4 4665.8 4687.3 4691.5 4702.9 4636.4 4642.1 4660.3 4660.6 4662.1 4651.0 4651.3 4658.1 4667.7 4679.9 4262.5 4355.3 4360.2 4380.5 4389.8 5472.3 5472.5 5495.7 5509.1 5521.4 5489.5 5495.8 5496.9 5507.6 5510.6 5807.7 5953.7 5986.3 5990.9 5999.5 4851.7 4854.8 4859.7 4871.4 4872.2 5926.0 6079.0 6140.0 6188.4 6290.4 4988.4 5001.8 5018.9 5022.0 5034.1 5058.6 5087.9 5101.9 5111.9 5120.7 5214.2 5414.2 5467.6 5471.9 5474.2 5944.3 5977.1 5982.9 6002.3 6014.6 1774.4 1789.6 1791.5 1795.6 1860.8 1561.3 1567.5 1588.4 1598.5 1613.5 5721.7 5745.7 5771.9 5780.6 5780.9 6174.3 6177.9 6196.4 6199.3 6203.6 5559.3 5720.1 5736.2 5753.9 5760.9 3231.6 3264.5 3265.0 3267.4 3286.4 6035.2 6350.3 6357.9 6382.7 6398.4 6305.9 6312.8 6314.5 6351.8 6354.8 6290.9 6403.3 6403.8 6418.5 6419.0 6146.1 6150.1 6160.4 6163.8 6185.9 5807.0 5965.7 6073.6 6112.4 6113.7 6201.7 6261.7 6330.7 6362.6 6367.0 5009.7 5038.0 5053.9 5062.7 5076.6 6046.8 6078.0 6142.6 6153.8 6162.7 5958.9 5984.9 5987.2 5988.8 6012.1 5813.2 5973.5 6018.8 6024.5 6038.2 5939.3 5988.6 5993.8 5997.3 6004.0 5881.7 5999.0 6006.2 6011.5 6023.2 5838.5 5843.2 5856.3 5863.4 5889.6 09:19:28 INFO - PID 15815 | 09:19:28 INFO - PID 15815 | [#5] yui.html Cycles:180 Average:17773.09 Median:45125.85 stddev:22949.46 (50.9%) stddev-sans-first:22977.98 09:19:28 INFO - PID 15815 | Values: 686.4 733.9 837.5 849.0 859.5 13447.5 13718.2 13739.9 13761.0 13873.7 14552.4 14619.8 14641.9 14646.4 14731.3 806.9 830.7 832.0 839.1 839.6 832.0 834.1 834.6 834.6 835.1 850.6 851.5 852.0 853.8 856.4 842.4 848.2 849.8 849.9 852.8 620.6 621.7 622.0 622.5 622.7 15714.4 15839.4 16086.8 16694.7 16775.7 15251.9 15481.6 15511.1 15814.0 16106.7 12232.5 12616.2 12751.3 12917.6 12923.5 3894.4 3937.1 3940.6 4008.5 4062.8 63130.7 73093.0 86588.6 86979.0 88958.6 11897.9 11901.2 11913.0 11960.8 12227.4 23958.5 24127.4 24239.0 24317.6 24406.0 8367.3 8398.4 8449.2 8522.2 8725.3 4317.1 4420.6 4425.7 4430.9 4481.8 3161.0 3188.5 3199.2 3200.7 3245.8 549.7 551.7 553.1 556.2 564.4 610.3 620.3 626.2 634.2 641.4 9898.3 10054.5 10251.8 10256.2 10456.6 739.1 759.7 763.5 774.3 778.6 4765.8 5171.4 5181.1 5185.8 5240.0 65511.0 78499.6 80918.2 83957.6 86433.6 69340.7 69641.7 70766.0 73504.6 74500.3 63929.4 63994.0 64799.4 64824.7 64864.7 10105.4 10294.3 10323.9 10400.0 10427.6 44972.2 45279.5 45418.0 45697.8 47601.8 53004.5 53351.0 53512.7 54767.5 54996.5 12378.3 12732.7 12980.6 13036.7 13200.3 13465.9 13512.6 13562.9 13669.0 13925.0 11000.2 11359.5 11439.7 11453.8 11945.5 11094.9 11232.1 11433.8 11934.3 11936.5 12619.6 12735.4 13014.2 13158.3 13696.4 11202.0 11262.3 11280.4 11554.8 11834.3 9354.3 9938.2 9946.8 9981.5 10035.1 09:19:28 INFO - PID 15815 | -------- Summary: end -------- 09:19:28 INFO - PID 15815 | 09:19:29 INFO - TEST-INFO | 15815: exit 0 09:19:29 INFO - TEST-OK | dromaeo_css | took 1116546ms 09:19:29 INFO - TEST-START | kraken 09:19:29 INFO - operating with platform_type : linux_ 09:19:29 INFO - Initialising browser for kraken test... 09:19:29 INFO - TEST-INFO | started process 16146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHW3T__/profile http://localhost:45854/getInfo.html) 09:19:33 INFO - PID 16146 | __metrics Screen width/height:1600/1200 09:19:33 INFO - PID 16146 | colorDepth:24 09:19:33 INFO - PID 16146 | Browser inner width/height: 1024/697 09:19:33 INFO - PID 16146 | __metrics 09:19:34 INFO - PID 16146 | 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:19:34 INFO - PID 16146 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:35 INFO - TEST-INFO | 16146: exit 0 09:19:35 INFO - Browser initialized. 09:19:35 INFO - Running cycle 1/1 for kraken test... 09:19:35 INFO - TEST-INFO | started process 16240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHW3T__/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:47 INFO - PID 16240 | RSS: Main: 145125376 09:19:47 INFO - PID 16240 | 09:19:49 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45854/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:50 INFO - PID 16240 | RSS: Main: 200581120 09:19:50 INFO - PID 16240 | 09:19:53 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45854/tests/kraken/driver.html?testName=audio-dft) 09:19:53 INFO - PID 16240 | RSS: Main: 179740672 09:19:53 INFO - PID 16240 | 09:19:56 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45854/tests/kraken/driver.html?testName=audio-fft) 09:19:57 INFO - PID 16240 | RSS: Main: 261083136 09:19:57 INFO - PID 16240 | 09:19:59 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45854/tests/kraken/driver.html?testName=audio-oscillator) 09:19:59 INFO - PID 16240 | RSS: Main: 171573248 09:19:59 INFO - PID 16240 | 09:20:02 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45854/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:02 INFO - PID 16240 | RSS: Main: 175939584 09:20:02 INFO - PID 16240 | 09:20:05 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45854/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:06 INFO - PID 16240 | RSS: Main: 215711744 09:20:06 INFO - PID 16240 | 09:20:09 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45854/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:10 INFO - PID 16240 | RSS: Main: 229289984 09:20:10 INFO - PID 16240 | 09:20:13 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45854/tests/kraken/driver.html?testName=json-parse-financial) 09:20:13 INFO - PID 16240 | RSS: Main: 240545792 09:20:13 INFO - PID 16240 | 09:20:15 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45854/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:15 INFO - PID 16240 | RSS: Main: 177922048 09:20:15 INFO - PID 16240 | 09:20:17 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:18 INFO - PID 16240 | RSS: Main: 185102336 09:20:18 INFO - PID 16240 | 09:20:20 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:20 INFO - PID 16240 | RSS: Main: 194469888 09:20:20 INFO - PID 16240 | 09:20:23 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:23 INFO - PID 16240 | RSS: Main: 203386880 09:20:23 INFO - PID 16240 | 09:20:26 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:27 INFO - PID 16240 | RSS: Main: 199467008 09:20:27 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:45854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45854/tests/kraken/driver.html?testName=ai-astar) 09:20:29 INFO - PID 16240 | RSS: Main: 267808768 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | __start_tp_report 09:20:29 INFO - PID 16240 | _x_x_mozilla_page_load 09:20:29 INFO - PID 16240 | _x_x_mozilla_page_load_details 09:20:29 INFO - PID 16240 | |i|pagename|runs| 09:20:29 INFO - PID 16240 | |0;ai-astar;106;113;112;114;114;114;115;114;111;113 09:20:29 INFO - PID 16240 | |1;audio-beat-detection;139;116;123;155;118;122;118;122;139;117 09:20:29 INFO - PID 16240 | |2;audio-dft;160;161;160;161;161;160;161;162;162;163 09:20:29 INFO - PID 16240 | |3;audio-fft;73;77;74;104;74;74;74;75;100;74 09:20:29 INFO - PID 16240 | |4;audio-oscillator;84;83;105;83;83;82;99;83;84;98 09:20:29 INFO - PID 16240 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 09:20:29 INFO - PID 16240 | |6;imaging-darkroom;146;147;146;147;147;146;148;147;146;147 09:20:29 INFO - PID 16240 | |7;imaging-desaturate;94;94;94;94;94;94;94;95;94;93 09:20:29 INFO - PID 16240 | |8;json-parse-financial;56;56;55;57;57;57;57;57;57;56 09:20:29 INFO - PID 16240 | |9;json-stringify-tinderbox;53;54;54;57;55;57;55;55;56;55 09:20:29 INFO - PID 16240 | |10;stanford-crypto-aes;79;80;79;79;79;80;78;78;87;79 09:20:29 INFO - PID 16240 | |11;stanford-crypto-ccm;124;132;126;131;124;125;127;125;167;126 09:20:29 INFO - PID 16240 | |12;stanford-crypto-pbkdf2;186;187;182;185;181;180;175;181;179;181 09:20:29 INFO - PID 16240 | |13;stanford-crypto-sha256-iterative;65;72;64;65;63;65;62;63;63;63 09:20:29 INFO - PID 16240 | __end_tp_report 09:20:29 INFO - PID 16240 | __start_cc_report 09:20:29 INFO - PID 16240 | _x_x_mozilla_cycle_collect,1714 09:20:29 INFO - PID 16240 | __end_cc_report 09:20:29 INFO - PID 16240 | __startTimestamp1489594829433__endTimestamp 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | ------- Summary: start ------- 09:20:29 INFO - PID 16240 | Number of tests: 14 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:2.59 (2.3%) stddev-sans-first:1.22 09:20:29 INFO - PID 16240 | Values: 106.0 113.0 112.0 114.0 114.0 114.0 115.0 114.0 111.0 113.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:122.50 stddev:13.00 (10.6%) stddev-sans-first:13.03 09:20:29 INFO - PID 16240 | Values: 139.0 116.0 123.0 155.0 118.0 122.0 118.0 122.0 139.0 117.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 09:20:29 INFO - PID 16240 | Values: 160.0 161.0 160.0 161.0 161.0 160.0 161.0 162.0 162.0 163.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#3] audio-fft Cycles:10 Average:79.90 Median:74.00 stddev:11.73 (15.9%) stddev-sans-first:12.18 09:20:29 INFO - PID 16240 | Values: 73.0 77.0 74.0 104.0 74.0 74.0 74.0 75.0 100.0 74.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:83.50 stddev:8.67 (10.4%) stddev-sans-first:9.05 09:20:29 INFO - PID 16240 | Values: 84.0 83.0 105.0 83.0 83.0 82.0 99.0 83.0 84.0 98.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:20:29 INFO - PID 16240 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:20:29 INFO - PID 16240 | Values: 146.0 147.0 146.0 147.0 147.0 146.0 148.0 147.0 146.0 147.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:20:29 INFO - PID 16240 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 93.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:0.71 (1.2%) stddev-sans-first:0.73 09:20:29 INFO - PID 16240 | Values: 56.0 56.0 55.0 57.0 57.0 57.0 57.0 57.0 57.0 56.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#9] json-stringify-tinderbox Cycles:10 Average:55.10 Median:55.00 stddev:1.29 (2.3%) stddev-sans-first:1.12 09:20:29 INFO - PID 16240 | Values: 53.0 54.0 54.0 57.0 55.0 57.0 55.0 55.0 56.0 55.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:2.62 (3.3%) stddev-sans-first:2.76 09:20:29 INFO - PID 16240 | Values: 79.0 80.0 79.0 79.0 79.0 80.0 78.0 78.0 87.0 79.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:126.50 stddev:13.05 (10.3%) stddev-sans-first:13.61 09:20:29 INFO - PID 16240 | Values: 124.0 132.0 126.0 131.0 124.0 125.0 127.0 125.0 167.0 126.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.70 Median:181.50 stddev:3.56 (2.0%) stddev-sans-first:3.42 09:20:29 INFO - PID 16240 | Values: 186.0 187.0 182.0 185.0 181.0 180.0 175.0 181.0 179.0 181.0 09:20:29 INFO - PID 16240 | 09:20:29 INFO - PID 16240 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.50 stddev:2.84 (4.4%) stddev-sans-first:3.00 09:20:29 INFO - PID 16240 | Values: 65.0 72.0 64.0 65.0 63.0 65.0 62.0 63.0 63.0 63.0 09:20:29 INFO - PID 16240 | -------- Summary: end -------- 09:20:29 INFO - PID 16240 | 09:20:30 INFO - TEST-INFO | 16240: exit 0 09:20:30 INFO - TEST-OK | kraken | took 60788ms 09:20:30 INFO - SUITE-END | took 1177s 09:20:30 INFO - Completed test suite (00:19:37) 09:20:30 INFO - javascript benchmark 09:20:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7528.039293601738, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3207.502837060256, 3224.6452890182077, 3256.609206895173, 3261.445554255777, 3280.245543524989, 5407.540359069484, 5431.484009019142, 5457.045017122003, 5490.121580547113, 5490.450954904512, 5438.809641662542, 5449.918251226235, 5453.427390124039, 5459.836204913859, 5511.58663100267, 4888.315635810989, 4925.261210818388, 4947.529984651453, 4956.727379994099, 4960.875978100558, 4928.408590969088, 4953.281774142747, 4965.577925876305, 4982.651214414992, 5011.548960593545, 4784.091022705709, 4946.0, 4960.032793605248, 5010.498950104997, 5026.195808670614, 4866.3673722416215, 4878.341423907798, 4911.68074075184, 4912.70523768575, 4913.975430122862, 4880.365552478191, 4887.437944636376, 4933.038057578774, 4940.876478088041, 4943.555080042814, 5373.81191658294, 5375.354957405137, 5398.2982212312545, 5414.133738601844, 5434.809781657663, 5831.300243970751, 5854.297484301911, 5857.179994800731, 5862.706864656793, 5906.675132867734, 5693.501021133231, 5705.885882282372, 5720.942790572082, 5748.7987920423, 5749.223854779635, 5467.535259502947, 5490.368607610096, 5490.752916118784, 5491.588130890199, 5492.176173573916, 5891.764329426775, 5967.343592204854, 5996.220491336098, 5999.310079340931, 6024.789132380346, 5685.488306052475, 5690.630109042899, 5691.544676425876, 5742.425757424224, 5744.080947048452, 5738.081906894877, 5794.188813566104, 5800.941990580124, 5842.444967728059, 5878.2064421302575, 5848.941510584839, 5850.3564607893095, 5853.443922827325, 5855.970720146457, 5871.4128587141795, 5739.713014349266, 5794.536437085023, 5804.9419505805245, 5827.912581311284, 5845.88308233833, 4536.047430039728, 4538.72767633943, 4554.4990051094355, 4556.157110934488, 4561.338605902126, 4035.6771458283265, 4044.2315959967514, 4050.25070361984, 4070.063885306396, 4081.6326530612387, 5421.783128674809, 5469.179623056494, 5535.667859928417, 5552.3892371839065, 5554.333479982428, 5856.502197313276, 5887.646741195542, 5901.0, 5928.140419639129, 5933.554983376264, 5501.4223506532035, 5506.972465137648, 5515.310586176728, 5532.0, 5532.363778165482, 4947.529984651453, 5018.774155163026, 5021.322121513622, 5024.57291130258, 5071.847844564669, 6020.397960204032, 6045.1234570987845, 6055.1522786810765, 6106.297775755667, 6107.84730381744, 5859.58982871212, 5889.411058894077, 5913.46778789911, 5931.169636251015, 5931.258592675916, 6113.877722445437, 6118.969405152946, 6122.204113465399, 6122.234720659918, 6123.938760612336, 5677.5457963362005, 5740.540756739534, 5748.798792042258, 5810.5351571875, 5828.329742079715, 5899.528037756883, 5907.17299578068, 5907.261592300963, 5907.5273978080795, 5938.346781853907, 5526.336839579276, 5758.71206439668, 5763.6541807491685, 5765.7117144141785, 5767.307923049261, 5690.886182276414, 5698.971505142447, 5708.4862362387585, 5755.71221438918, 5769.105788602773, 5637.9718101409235, 5677.261955945701, 5682.857928551819, 5687.431256874445, 5714.514266287412, 5696.373398926197, 5703.287089113861, 5746.367899531132, 5757.625754325955, 5759.94240057594, 5524.751386187711, 5530.364008139116, 5587.273654424899, 5591.776328946959, 5592.356878958809], "value": 5390.32321924246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.7613835157696, 744.5755919126099, 765.03605457124, 815.0463957170114, 828.2669837194081, 28642.28394290144, 36958.445623315674, 37646.8117659412, 37653.81173094131, 37737.24525509488, 42457.36313955286, 42862.57137428625, 42897.57102428983, 42920.0, 42989.0, 6902.482313826458, 7162.785116446515, 8455.957720211405, 8595.44129631572, 8596.441231319945, 9886.802263954716, 9959.90040099597, 10218.0, 10219.335743176707, 10227.539760710786, 10234.948825255864, 10409.167266618653, 10460.4769761512, 10465.31975421599, 10466.0, 10327.586896524168, 10415.68752937413, 10416.739581510446, 10523.78952420955, 10558.419286939215, 9476.857847132298, 9573.760655983586, 9628.074385122933, 9850.014998500164, 9883.752906177404, 39459.60540394588, 39547.20905581901, 39698.0, 39732.20535589272, 39798.60201397978, 3798.3542797724454, 3818.179091495314, 3832.9041773955596, 3841.2701586698718, 3899.473571067898, 28521.286967825763, 28544.0, 28591.0, 29414.558781618292, 29550.0, 4163.625273725379, 4197.580241975808, 4211.978940105279, 4295.978520107379, 4431.202383570956, 209572.0, 209708.0, 210011.0, 210818.94590527102, 211233.0, 50048.49951500511, 50226.246606300934, 50474.24288635673, 50500.74749626228, 50528.0, 52212.47787522152, 52598.737006314725, 52726.0, 52768.472315277126, 52778.20832687513, 14958.177300248373, 15335.386584536494, 15352.769708454382, 15365.0, 15368.542078032353, 10190.592376304865, 10435.43468479719, 12320.383980801103, 12403.193792403645, 12409.565665201675, 12799.232046077264, 12959.0, 13028.513278927356, 13164.934175329254, 13552.390142443614, 5255.582432081209, 5339.145736682112, 5342.9465705342445, 5365.946340536622, 5492.642978206404, 915.9175674189355, 934.6728644974205, 945.9763505912407, 948.4688574398222, 949.781550243434, 24467.0, 25995.220143395727, 26267.474650506883, 26393.472130557664, 26445.206643800717, 990.2474119669104, 990.4898977026837, 998.9700308990742, 1010.8938561451089, 1022.5807418958192, 13089.738205235843, 13622.931885340708, 13990.370433330525, 14024.859751402559, 14066.226357550433, 290886.0, 291322.0, 291860.3023516227, 292074.0, 293660.0, 53798.731006344715, 53844.461555384725, 53900.73049634805, 53949.1907621386, 53990.0, 1361.8229630148016, 1376.2361889151616, 1399.3283224052511, 1407.4933024111108, 1414.5402744108205, 23916.76083239215, 24125.924491001977, 24903.875480623206, 24998.500029998933, 25044.62433063542, 22185.334439966828, 22297.331080067623, 22377.0, 22388.440288992904, 22408.215712449986, 265668.0, 265732.0, 266032.0, 266663.0, 266919.0, 25355.492890142465, 28092.85953570301, 32858.34283314273, 32941.835290824354, 33024.834875825465, 35827.74602888886, 36060.4590931131, 36541.26917461689, 36706.4494032595, 36733.08167295732, 30885.0, 30901.69098308988, 31182.532262016543, 31580.0, 32319.35361292808, 30225.395492089596, 30366.544501832934, 30497.54253686152, 30983.69016309808, 31822.727090916105, 35348.29303413866, 36075.639243608275, 36089.81955090208, 36154.8192259037, 36182.27635447329, 27586.172414827586, 30753.078741136957, 30796.53805192879, 30827.229319267197, 30955.851520536875, 23828.761712382653, 24042.278731638078, 24304.392390190387, 24590.754092458847, 24692.876535617208], "value": 17324.56125790844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.78379242793767, 431.01298027516975, 432.3795353667487, 443.09608398866317, 454.14393867559653, 18411.53971150722, 19585.314513992013, 20413.0, 21568.813715245655, 21684.132634694593, 19874.602507949832, 20248.49378765528, 20863.37409877702, 20896.791032089674, 21055.57888842226, 6218.440340369366, 6681.966590167067, 6683.53215274931, 6684.632345221011, 6689.565178263421, 6378.649174295412, 6497.382748638872, 6557.475401967855, 6580.572262802926, 6600.108985286973, 6385.968070159666, 6597.439217666504, 6606.108175396308, 6608.041833934077, 6608.834779130536, 6478.093066970628, 6571.7371305147735, 6587.835304117388, 6589.703463344137, 6590.1432813734145, 6418.8716225675225, 6428.132202152697, 6982.581045137308, 7263.164736055315, 7318.085239345082, 23009.88495057514, 23358.76641233583, 24421.0, 24499.0, 24642.137525186532, 2788.888444462219, 2821.6331876856084, 2853.9714602853915, 2873.8563071846534, 2874.928126796826, 15257.694846103015, 15555.30001149951, 15762.763558546516, 16141.91929040359, 16406.04306911147, 2126.8936553172434, 2136.6688163334707, 2170.1753333733236, 2194.2758889566403, 2224.9110035598555, 84429.57785211035, 103543.48228258886, 103586.99999999924, 104223.0, 105100.0, 17243.3964811231, 17820.376286829898, 18097.819021809875, 18168.364107256184, 18171.72742408865, 33424.83287583546, 33506.66493335084, 33616.15959601029, 33689.0, 33726.66273337285, 9608.807823843485, 9954.850677239847, 10039.598416063422, 10054.693274205469, 10325.277230593787, 8198.754037378889, 8207.753767386868, 8439.324854011787, 8525.488470691778, 8554.35842311829, 9436.575354109082, 9448.57481413338, 9769.267304952156, 9909.950450247703, 9970.302078854556, 3676.852925882935, 3808.8383043171543, 3868.3172819823467, 3980.6019398060544, 3999.8800035998966, 872.7381785464336, 900.3337530227584, 900.7883147460342, 912.9777542742856, 925.7870689741397, 21292.467688307606, 22398.77601223967, 22521.0, 22563.323100307018, 22623.321300361014, 902.201551626815, 905.0994260710635, 907.9546022698838, 912.9224015958717, 932.2122806228726, 12198.57304994321, 12268.263904165779, 12482.687932801753, 12491.937540312421, 12514.124011319302, 600923.0, 620668.0, 631482.0, 641604.0, 642042.0, 85374.57312713396, 86714.0, 91964.0, 106405.0, 108749.0, 1179.9469023893946, 1219.9329036902877, 1262.665393670678, 1272.1413046193782, 1273.8216649669055, 19491.317803876795, 19517.70723439121, 20411.693824592345, 20442.69335959932, 20444.488887777923, 47162.0, 47215.527844722485, 48879.0, 49105.50894491009, 49465.50534494609, 57948.4205157943, 60787.69606151941, 69830.65084674544, 70823.64588177027, 71148.64425677838, 12411.937940310241, 13142.0, 13169.604911852659, 13223.404946777226, 13363.198208107544, 14396.85603143955, 14418.567442976728, 14471.78292325637, 14525.782113268098, 14530.927345363205, 9844.655437059668, 12074.335911524598, 12377.814332785196, 12628.0, 12989.610311690287, 12254.877451225373, 12508.374581270718, 12732.554360597334, 12833.42249598789, 12944.15862968904, 11836.349000805038, 12321.383930803244, 12367.443465044274, 12872.678183045498, 13064.150830196008, 12967.286799226133, 13056.477740890257, 13070.281134537692, 13159.407826647632, 13514.324283785574, 11888.881111189123, 12507.187032842836, 12568.560100396442, 12610.83841834884, 12625.684357891127], "value": 11234.51315929795, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1486.9405223791048, 1509.433962264151, 1513.1223890143717, 1522.1704171226677, 1597.7603359496077, 10124.291299609022, 10552.947235263813, 10566.630167944124, 10568.78862422751, 10570.101541368973, 10299.794004119913, 10511.894881051207, 10748.892511074888, 10792.73018174546, 10810.459477026137, 2179.7166368372127, 2239.361781892161, 2239.9888000560054, 2240.607893618618, 2245.528439027798, 2170.739511258651, 2208.3816531371162, 2210.2153735423935, 2211.7677643847405, 2212.126210146991, 2162.2540223622823, 2180.7819218078225, 2188.245055455865, 2192.506685995646, 2200.90095945682, 2102.8843413612244, 2123.9469013274716, 2141.668041453577, 2142.73215848019, 2145.452909508081, 2112.324056301984, 2171.6416791229426, 2178.096090122597, 2179.52050548879, 2182.945426364338, 10476.371417714885, 10538.789224215549, 10570.312929659547, 10570.682879513626, 10581.312214706097, 5166.276721259027, 5390.6226564140525, 5392.272043274186, 5396.244525766396, 5397.8380648580605, 3085.213270616001, 3278.50822376643, 3391.372596069735, 3394.8472318745717, 3404.3872103021445, 1251.330538162079, 1286.8906142977858, 1324.7681655710212, 1403.4666826605824, 1407.1908652524737, 12767.93616031914, 13771.311434428339, 13938.58184254474, 14846.70306593873, 15077.170755608437, 4306.978465107654, 4415.4259946206785, 4524.072565124142, 4594.517575654508, 4599.563041511051, 13209.669758256121, 13211.273379964003, 13213.537526186537, 13227.338633068308, 13232.0, 4584.770761461914, 4713.858584242478, 5343.679379237258, 5465.9180112298345, 5520.641158324696, 4908.165611845923, 4968.478309777493, 4981.32752078472, 4982.202847544376, 4982.750862456863, 3781.149241420658, 3793.203427280247, 3905.453236546886, 3925.509311336083, 3941.0935484838074, 1038.5170895533613, 1051.7948999944938, 1072.2601405030505, 1077.224398433127, 1083.6748975307376, 989.5992123190119, 990.9702708918744, 1012.2458768217725, 1019.495349801838, 1033.3748082410184, 6657.933420665829, 6860.142482189726, 6881.277465866112, 6907.481938854606, 7043.894341584881, 1014.4217795856291, 1016.872890888639, 1039.6621098143135, 1060.6977011551671, 1069.4973362519604, 4403.0, 4440.644748420119, 4464.0, 4484.192845287879, 4516.706414083074, 12939.482420703067, 13408.932955335162, 13717.931410342884, 13946.093503922213, 14009.369578368998, 13590.184588924696, 13645.59063228085, 13808.585742427744, 13822.585322440344, 13839.308034598229, 8296.377771667148, 9846.556904939152, 10091.596336146471, 10091.848622270672, 10103.393796372242, 6040.7583696651645, 6615.272320044887, 6625.668716564249, 6666.0, 6765.830854228684, 11121.388323642277, 11322.60370887015, 11405.885941140483, 11522.078233741113, 11756.58851940178, 11649.534018639159, 11670.824937625772, 11748.765024699629, 11895.524179033087, 11987.0, 8350.331973442231, 8484.787880303042, 8562.957185214034, 8571.742847714579, 8573.74278771638, 9747.0, 9854.162396196403, 9877.357971731815, 9929.156021738234, 10003.699889003341, 8358.83282334362, 8424.115467875785, 8527.701530446404, 8790.956045219733, 8793.956030220064, 8710.085441028854, 8786.077461866413, 8820.64717411322, 8847.11528847136, 8915.465072095694, 7714.498557593738, 7815.921840781747, 7861.921380786119, 7877.409194310564, 7880.684772609261, 5786.797462088975, 5893.145493903446, 5914.911276330772, 6059.394060593905, 6065.727042283196, 5967.6121052131475, 5980.5215582754145, 6021.87956240864, 6099.969500152471, 6112.083187521729], "value": 5020.8618842336045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.9824424886847, 403.3566461493918, 410.6181251436164, 414.7345698752797, 422.3263894088928, 5587.357453892803, 5709.857253568658, 5720.770034442594, 5727.284089488814, 5853.648781073139, 5585.162225666142, 5594.58040646951, 5622.718864056801, 5623.915641265385, 5643.181738647893, 4678.812847486097, 4684.695494792828, 4691.272852707836, 4697.976510117444, 4705.1530724469585, 4487.416635837353, 4665.83669571565, 4687.250039993602, 4691.483936766953, 4702.90594188116, 4636.397268355109, 4642.07158568286, 4660.300954856764, 4660.580547750702, 4662.114198302312, 4651.000034992493, 4651.255799072149, 4658.114958157939, 4667.696599721007, 4679.900223447487, 4262.488501379823, 4355.281378572532, 4360.236958532244, 4380.518143004267, 4389.770864158041, 5472.288602481692, 5472.480114389126, 5495.670259784426, 5509.118541033456, 5521.3926468088475, 5489.505944464978, 5495.8351249462185, 5496.945030549683, 5507.614466987313, 5510.586705997066, 5807.738651760681, 5953.702314884238, 5986.28164620244, 5990.880182396328, 5999.520038396918, 4851.660383773137, 4854.805807767685, 4859.708417494961, 4871.415430148405, 4872.244802055651, 5925.970370148165, 6078.966575682145, 6140.0, 6188.381161883821, 6290.433860952537, 4988.35151430319, 5001.8499445016705, 5018.920931999638, 5021.974890125526, 5034.11902916988, 5058.595312375001, 5087.949120508748, 5101.897962040738, 5111.923321150186, 5120.743962801845, 5214.165733482625, 5414.160805075219, 5467.644603100865, 5471.890562188813, 5474.206240095244, 5944.256967879015, 5977.133315669291, 5982.880342393215, 6002.339742628307, 6014.639121652627, 1774.4233124234675, 1789.5526118470382, 1791.4536066499581, 1795.6408718256139, 1860.7674040744907, 1561.2505997121445, 1567.5454118305563, 1588.4202266172763, 1598.5204438668352, 1613.475620423367, 5721.685307308138, 5745.683987380734, 5771.932192544393, 5780.566457515619, 5780.942190578125, 6174.289956654952, 6177.907331390033, 6196.411340922605, 6199.256089269316, 6203.596766210182, 5559.305086864142, 5720.11338242573, 5736.225609542742, 5753.88492230153, 5760.884782304331, 3231.644519102897, 3264.477683570665, 3264.983675081657, 3267.4118658641196, 3286.35448463984, 6035.215421995113, 6350.333215012357, 6357.904631430532, 6382.7130276677435, 6398.392152745575, 6305.905411418832, 6312.810615681537, 6314.463270621956, 6351.809445716636, 6354.777582784581, 6290.9056364155485, 6403.327650596713, 6403.807885763342, 6418.454431373292, 6419.005054216604, 6146.108814222002, 6150.1082343059115, 6160.414760597736, 6163.753449861955, 6185.876282474415, 5807.0, 5965.73154208053, 6073.605215660968, 6112.419320164578, 6113.663748493876, 6201.720922558586, 6261.718222680081, 6330.746770129143, 6362.554621176473, 6366.9681651591445, 5009.674371165863, 5038.042771873333, 5053.873653158554, 5062.670926389773, 5076.568491678248, 6046.84882877914, 6078.0, 6142.57002009855, 6153.84615384619, 6162.661053642093, 5958.880822383441, 5984.910226346521, 5987.191729116514, 5988.790392336247, 6012.098185272244, 5813.186153938367, 5973.461278956513, 6018.759249629966, 6024.548158888013, 6038.184845045863, 5939.3466718661775, 5988.610740301867, 5993.820185394445, 5997.280326360865, 6004.0, 5881.735321910439, 5998.970005149947, 6006.249218847644, 6011.489023433057, 6023.1868697726995, 5838.474537291494, 5843.211166492469, 5856.267966504187, 5863.442972917736, 5889.58772885911], "value": 4791.349070562569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.3719696477723, 733.8715724748167, 837.51842690453, 848.9830203395936, 859.4542465534386, 13447.529336473226, 13718.245496497688, 13739.862601373983, 13761.0, 13873.722525549496, 14552.417903283882, 14619.780703289432, 14641.926790366033, 14646.41414343425, 14731.263436828169, 806.9475484093515, 830.667732906836, 831.9625616847226, 839.1147339556766, 839.6263662670115, 831.9500829950192, 834.1283358889945, 834.5576844272546, 834.6119054639591, 835.0563862834988, 850.6342272822684, 851.4763420496412, 851.9616617252208, 853.8292341531687, 856.3577317012241, 842.3850589069982, 848.1518481518482, 849.7705619482767, 849.8512760266929, 852.8038551133212, 620.5842085802523, 621.6767281013892, 621.9968900155516, 622.483338828771, 622.7135517661881, 15714.44999425026, 15839.445619403381, 16086.75869861953, 16694.66610667792, 16775.748363774554, 15251.856110791625, 15481.612959675986, 15511.146886921215, 15814.0, 16106.677866442606, 12232.510699572007, 12616.243025418504, 12751.298678572675, 12917.612471625866, 12923.547675831302, 3894.3768996960634, 3937.0944682723134, 3940.566537680853, 4008.518977722663, 4062.7765472898977, 63130.68434657843, 73093.0, 86588.56705716494, 86979.0, 88958.5552072242, 11897.881021189678, 11901.166918315808, 11913.04695624348, 11960.820587691192, 12227.449764760608, 23958.52082958366, 24127.396815079413, 24239.03043878225, 24317.635235471484, 24406.023759049793, 8367.28878045373, 8398.370122240856, 8449.197326253996, 8522.232999030117, 8725.258353040063, 4317.0934103838545, 4420.624246939046, 4425.690201685916, 4430.867073987785, 4481.75350355727, 3160.9683903161135, 3188.505781603855, 3199.232184275757, 3200.6639302873327, 3245.8214798185854, 549.6839317392515, 551.737924485866, 553.0818840724459, 556.1962963517678, 564.4327450911808, 610.2585358789092, 620.3486339343671, 626.2078470734524, 634.1660716158237, 641.4066988036049, 9898.257630677585, 10054.547545360476, 10251.84622230667, 10256.230782691327, 10456.63401780934, 739.0909181706423, 759.6880029714002, 763.522798251093, 774.3263360876074, 778.5523324088558, 4765.8093676252565, 5171.4311425743135, 5181.093308670967, 5185.766640501187, 5240.0, 65511.0, 78499.60750196327, 80918.19081809225, 83957.58021209855, 86433.56783216044, 69340.65329673319, 69641.65179174072, 70766.0, 73504.63247683727, 74500.25499745042, 63929.36070639234, 63994.0, 64799.352006479334, 64824.67587662031, 64864.675676621315, 10105.372343873798, 10294.330868493524, 10323.94838025805, 10400.0, 10427.582896684047, 44972.15362002919, 45279.54720452753, 45418.0, 45697.77151114356, 47601.761991191204, 53004.46995529995, 53351.0, 53512.73243633757, 54767.45232547623, 54996.45003549913, 12378.319192444502, 12732.74534509286, 12980.610581682564, 13036.739265214832, 13200.273984930922, 13465.865341346462, 13512.557481428794, 13562.93218533901, 13669.0, 13925.0, 11000.174986876176, 11359.48882300282, 11439.714007149887, 11453.770924581295, 11945.462454189408, 11094.944525277322, 11232.101431885592, 11433.771324573296, 11934.28394296345, 11936.52253909834, 12619.621411357673, 12735.363231838557, 13014.21914685122, 13158.342082895626, 13696.383662735001, 11202.0, 11262.324260544394, 11280.379579123228, 11554.826677600011, 11834.349110798988, 9354.251659867059, 9938.204943604349, 9946.751331216778, 9981.500924953578, 10035.147012503727], "value": 7210.881455270528, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 112.0, 114.0, 114.0, 114.0, 115.0, 114.0, 111.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 116.0, 123.0, 155.0, 118.0, 122.0, 118.0, 122.0, 139.0, 117.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 161.0, 160.0, 161.0, 162.0, 162.0, 163.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 74.0, 104.0, 74.0, 74.0, 74.0, 75.0, 100.0, 74.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 105.0, 83.0, 83.0, 82.0, 99.0, 83.0, 84.0, 98.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 148.0, 147.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 57.0, 55.0, 57.0, 55.0, 55.0, 56.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 79.0, 79.0, 80.0, 78.0, 78.0, 87.0, 79.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 132.0, 126.0, 131.0, 124.0, 125.0, 127.0, 125.0, 167.0, 126.0], "value": 130.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 182.0, 185.0, 181.0, 180.0, 175.0, 181.0, 179.0, 181.0], "value": 181.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 64.0, 65.0, 63.0, 65.0, 62.0, 63.0, 63.0, 63.0], "value": 64.5, "unit": "score"}]}]} 09:20:30 INFO - Return code: 0 09:20:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:30 ERROR - # TBPL SUCCESS # 09:20:30 INFO - Running post-action listener: _resource_record_post_action 09:20:30 INFO - [mozharness: 2017-03-15 16:20:30.753739Z] Finished run-tests step (success) 09:20:30 INFO - Running post-run listener: _resource_record_post_run 09:20:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 370520064; Read time: 12; Write time: 247268 09:20:30 INFO - TinderboxPrint: CPU usage
12.9% 09:20:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:20:30 INFO - TinderboxPrint: I/O write bytes / time
370,520,064 / 247,268 09:20:30 INFO - TinderboxPrint: CPU idle
8,296.7 (87.1%) 09:20:30 INFO - TinderboxPrint: CPU user
1,189.0 (12.5%) 09:20:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:20:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110735360; Read time: 0; Write time: 89588 09:20:30 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 259784704; Read time: 12; Write time: 157680 09:20:31 INFO - Running post-run listener: _upload_blobber_files 09:20:31 INFO - Blob upload gear active. 09:20:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:31 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:20:31 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:20:31 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:20:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:32 INFO - (blobuploader) - INFO - Done attempting. 09:20:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:35 INFO - (blobuploader) - INFO - Done attempting. 09:20:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:20:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:36 INFO - (blobuploader) - INFO - Done attempting. 09:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:37 INFO - (blobuploader) - INFO - Done attempting. 09:20:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:37 INFO - Return code: 0 09:20:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5598fd2a00588e9f4a533f0909616c8363f231076a802141f283a478a7f7bb6ddc0bc3876fd476f26fe3ea7639431bc41e0197ed330ca0ac1bcd334d8b1fbecd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a963ef11f1f52b9e825a8d74bde032d5b9f8418418a9ece81b87ebc6cb149f64039149cd356e681971a17c79a629bc27ecaeb21ff8ff43527ce4dae0ef5cc01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e50007770e91fadd555dd8c10446f646d1c4bad65bc4d8ac9cd05748ff288059340e19028011e65bf54eb900363466d379b92251abb59f493cc69b762db51576", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b380678fc54cac52a5a14691c1b92b3d2a93d3072f8d4dd0ad1994b937ed8b055714ea42ea90275cb3d22a1a5e2114e0f22e2327d4430f67d81544fccfc3aac"} 09:20:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:37 INFO - Contents: 09:20:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5598fd2a00588e9f4a533f0909616c8363f231076a802141f283a478a7f7bb6ddc0bc3876fd476f26fe3ea7639431bc41e0197ed330ca0ac1bcd334d8b1fbecd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a963ef11f1f52b9e825a8d74bde032d5b9f8418418a9ece81b87ebc6cb149f64039149cd356e681971a17c79a629bc27ecaeb21ff8ff43527ce4dae0ef5cc01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e50007770e91fadd555dd8c10446f646d1c4bad65bc4d8ac9cd05748ff288059340e19028011e65bf54eb900363466d379b92251abb59f493cc69b762db51576", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b380678fc54cac52a5a14691c1b92b3d2a93d3072f8d4dd0ad1994b937ed8b055714ea42ea90275cb3d22a1a5e2114e0f22e2327d4430f67d81544fccfc3aac"} 09:20:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:37 INFO - Copying logs to upload dir... 09:20:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.551705 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-15 09:20:37.440830) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 09:20:37.444478) ========= 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=ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5598fd2a00588e9f4a533f0909616c8363f231076a802141f283a478a7f7bb6ddc0bc3876fd476f26fe3ea7639431bc41e0197ed330ca0ac1bcd334d8b1fbecd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a963ef11f1f52b9e825a8d74bde032d5b9f8418418a9ece81b87ebc6cb149f64039149cd356e681971a17c79a629bc27ecaeb21ff8ff43527ce4dae0ef5cc01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e50007770e91fadd555dd8c10446f646d1c4bad65bc4d8ac9cd05748ff288059340e19028011e65bf54eb900363466d379b92251abb59f493cc69b762db51576", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b380678fc54cac52a5a14691c1b92b3d2a93d3072f8d4dd0ad1994b937ed8b055714ea42ea90275cb3d22a1a5e2114e0f22e2327d4430f67d81544fccfc3aac"} build_url:https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/bnwN-GwfR6y_y5esE2oOKw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5598fd2a00588e9f4a533f0909616c8363f231076a802141f283a478a7f7bb6ddc0bc3876fd476f26fe3ea7639431bc41e0197ed330ca0ac1bcd334d8b1fbecd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a963ef11f1f52b9e825a8d74bde032d5b9f8418418a9ece81b87ebc6cb149f64039149cd356e681971a17c79a629bc27ecaeb21ff8ff43527ce4dae0ef5cc01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e50007770e91fadd555dd8c10446f646d1c4bad65bc4d8ac9cd05748ff288059340e19028011e65bf54eb900363466d379b92251abb59f493cc69b762db51576", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b380678fc54cac52a5a14691c1b92b3d2a93d3072f8d4dd0ad1994b937ed8b055714ea42ea90275cb3d22a1a5e2114e0f22e2327d4430f67d81544fccfc3aac"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 09:20:37.469523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 09:20:37.469860) ========= 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=ed14279e38b05397cf4aa391000001a4-1489593582.313666-1112933304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 09:20:37.553049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 09:20:37.553393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 09:20:37.553689) ========= ========= Total master_lag: 0.16 =========