builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-062 starttime: 1483823169.41 results: success (0) revision: f40258de5ee269f22833bdcc36dff621814349cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.410694) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.411242) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.411596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.427265) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.427579) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-07 13:06:10-- 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% 68.7M=0s 2017-01-07 13:06:10 (68.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.589161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.589957) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021398 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.640766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:09.641167) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f40258de5ee269f22833bdcc36dff621814349cd --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 f40258de5ee269f22833bdcc36dff621814349cd --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=a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-07 13:06:10,748 truncating revision to first 12 chars 2017-01-07 13:06:10,748 Setting DEBUG logging. 2017-01-07 13:06:10,748 attempt 1/10 2017-01-07 13:06:10,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f40258de5ee2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 13:06:11,087 unpacking tar archive at: mozilla-central-f40258de5ee2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632737 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:10.293074) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:10.293457) ========= script_repo_revision: f40258de5ee269f22833bdcc36dff621814349cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:10.293921) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:10.294226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 13:06:10.312679) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-07 13:06:10.313108) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:06:11 INFO - MultiFileLogger online at 20170107 13:06:11 in /builds/slave/test 13:06:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 13:06:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:06:11 INFO - {'append_to_log': False, 13:06:11 INFO - 'base_work_dir': '/builds/slave/test', 13:06:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 13:06:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:06:11 INFO - 'branch': 'Firefox-Non-PGO', 13:06:11 INFO - 'buildbot_json_path': 'buildprops.json', 13:06:11 INFO - 'config_files': ('talos/linux_config.py',), 13:06:11 INFO - 'default_actions': ('clobber', 13:06:11 INFO - 'read-buildbot-config', 13:06:11 INFO - 'download-and-extract', 13:06:11 INFO - 'populate-webroot', 13:06:11 INFO - 'create-virtualenv', 13:06:11 INFO - 'install', 13:06:11 INFO - 'run-tests'), 13:06:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:06:11 INFO - 'download_minidump_stackwalk': True, 13:06:11 INFO - 'download_symbols': 'ondemand', 13:06:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:06:11 INFO - 'tooltool.py': '/tools/tooltool.py', 13:06:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:06:11 INFO - '/tools/misc-python/virtualenv.py')}, 13:06:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:06:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:06:11 INFO - 'installer_path': 'installer.exe', 13:06:11 INFO - 'log_level': 'info', 13:06:11 INFO - 'log_name': 'talos', 13:06:11 INFO - 'log_to_console': True, 13:06:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:06:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:06:11 INFO - 'opt_config_files': (), 13:06:11 INFO - 'pip_index': False, 13:06:11 INFO - 'sps_profile': False, 13:06:11 INFO - 'sps_profile_interval': 0, 13:06:11 INFO - 'suite': 'dromaeojs', 13:06:11 INFO - 'system_bits': '32', 13:06:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:06:11 INFO - 'title': 'talos-linux64-ix-062', 13:06:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:06:11 INFO - 'use_talos_json': True, 13:06:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:06:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:06:11 INFO - 'work_dir': 'build'} 13:06:11 INFO - [mozharness: 2017-01-07 21:06:11.523053Z] Running clobber step. 13:06:11 INFO - Running pre-action listener: _resource_record_pre_action 13:06:11 INFO - Running main action method: clobber 13:06:11 INFO - rmtree: /builds/slave/test/build 13:06:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:06:11 INFO - Running post-action listener: _resource_record_post_action 13:06:11 INFO - [mozharness: 2017-01-07 21:06:11.990883Z] Finished clobber step (success) 13:06:11 INFO - [mozharness: 2017-01-07 21:06:11.990985Z] Running read-buildbot-config step. 13:06:11 INFO - Running pre-action listener: _resource_record_pre_action 13:06:11 INFO - Running main action method: read_buildbot_config 13:06:11 INFO - Using buildbot properties: 13:06:11 INFO - { 13:06:11 INFO - "project": "", 13:06:11 INFO - "product": "firefox", 13:06:11 INFO - "who": "ryanvm@gmail.com", 13:06:11 INFO - "installer_path": "public/build/target.tar.bz2", 13:06:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 13:06:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 13:06:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 13:06:11 INFO - "stage_platform": "linux64", 13:06:11 INFO - "basedir": "/builds/slave/test", 13:06:11 INFO - "buildnumber": 905, 13:06:11 INFO - "platform": "ubuntu64_hw", 13:06:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:06:11 INFO - "slavebuilddir": "test", 13:06:11 INFO - "taskId": "LanT5p5NSFSQ3OTnzHvqAA", 13:06:11 INFO - "branch": "mozilla-central", 13:06:11 INFO - "script_repo_revision": "production", 13:06:11 INFO - "revision": "f40258de5ee269f22833bdcc36dff621814349cd", 13:06:11 INFO - "slavename": "talos-linux64-ix-062", 13:06:11 INFO - "repo_path": "mozilla-central" 13:06:11 INFO - } 13:06:11 INFO - Finding installer, test and symbols from parent task. 13:06:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LanT5p5NSFSQ3OTnzHvqAA'}, attempt #1 13:06:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LanT5p5NSFSQ3OTnzHvqAA'}, attempt #1 13:06:12 INFO - Task dependencies: GKnLaoKSQ2SAyCTQjylZHQ 13:06:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ'}, attempt #1 13:06:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2 13:06:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/test_packages.json 13:06:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.crashreporter-symbols.zip 13:06:13 INFO - Running post-action listener: _resource_record_post_action 13:06:13 INFO - [mozharness: 2017-01-07 21:06:13.436984Z] Finished read-buildbot-config step (success) 13:06:13 INFO - [mozharness: 2017-01-07 21:06:13.437202Z] Running download-and-extract step. 13:06:13 INFO - Running pre-action listener: _resource_record_pre_action 13:06:13 INFO - Running main action method: download_and_extract 13:06:13 INFO - mkdir: /builds/slave/test/build/tests 13:06:13 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')]} 13:06:13 INFO - trying https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.test_packages.json 13:06:13 INFO - Downloading https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:06:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:06:14 INFO - Downloaded 896 bytes. 13:06:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:06:14 INFO - Using the following test package requirements: 13:06:14 INFO - {u'common': [u'target.common.tests.zip'], 13:06:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:06:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:06:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:06:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:06:14 INFO - u'mozbase': [u'target.common.tests.zip'], 13:06:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:06:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:06:14 INFO - u'web-platform': [u'target.common.tests.zip', 13:06:14 INFO - u'target.web-platform.tests.zip'], 13:06:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:06:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:06:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.common.tests.zip 13:06:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:06:14 INFO - Fetch https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.common.tests.zip into memory 13:06:15 INFO - Expected file size: 20251356 13:06:15 INFO - Obtained file size: 20251356 13:06:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:06:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.common.tests.zip 13:06:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:06:19 INFO - Fetch https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.common.tests.zip into memory 13:06:20 INFO - Expected file size: 20251356 13:06:20 INFO - Obtained file size: 20251356 13:06:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.talos.tests.zip 13:06:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:06:24 INFO - Fetch https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.talos.tests.zip into memory 13:06:26 INFO - Expected file size: 14403537 13:06:26 INFO - Obtained file size: 14403537 13:06:26 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')]} 13:06:26 INFO - trying https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2 13:06:26 INFO - Downloading https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:06:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:06:28 INFO - Downloaded 55915749 bytes. 13:06:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2 13:06:28 INFO - mkdir: /builds/slave/test/properties 13:06:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:06:28 INFO - Writing to file /builds/slave/test/properties/build_url 13:06:28 INFO - Contents: 13:06:28 INFO - build_url:https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2 13:06:28 INFO - Running post-action listener: _resource_record_post_action 13:06:28 INFO - Running post-action listener: set_extra_try_arguments 13:06:28 INFO - [mozharness: 2017-01-07 21:06:28.744120Z] Finished download-and-extract step (success) 13:06:28 INFO - [mozharness: 2017-01-07 21:06:28.744257Z] Running populate-webroot step. 13:06:28 INFO - Running pre-action listener: _resource_record_pre_action 13:06:28 INFO - Running main action method: populate_webroot 13:06:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:06:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:06:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:06:28 INFO - '--tppagecycles', 13:06:28 INFO - '7'], 13:06:28 INFO - 'tests': ['tsvgm']}}, 13:06:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:06:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:06:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:06:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:06:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:06:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:06:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['damp', 'tps']}, 13:06:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'tests': ['damp', 'tps']}, 13:06:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['dromaeo_dom']}, 13:06:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:06:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['basic_compositor_video']}, 13:06:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:06:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['a11yr', 13:06:28 INFO - 'ts_paint', 13:06:28 INFO - 'tpaint', 13:06:28 INFO - 'sessionrestore', 13:06:28 INFO - 'sessionrestore_no_auto_restore', 13:06:28 INFO - 'tabpaint']}, 13:06:28 INFO - 'other-e10s': {'tests': ['a11yr', 13:06:28 INFO - 'ts_paint', 13:06:28 INFO - 'tpaint', 13:06:28 INFO - 'sessionrestore', 13:06:28 INFO - 'sessionrestore_no_auto_restore', 13:06:28 INFO - 'tabpaint']}, 13:06:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:06:28 INFO - 'ts_paint', 13:06:28 INFO - 'tpaint', 13:06:28 INFO - 'sessionrestore', 13:06:28 INFO - 'sessionrestore_no_auto_restore', 13:06:28 INFO - 'tabpaint']}, 13:06:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:06:28 INFO - 'ts_paint', 13:06:28 INFO - 'tpaint', 13:06:28 INFO - 'sessionrestore', 13:06:28 INFO - 'sessionrestore_no_auto_restore', 13:06:28 INFO - 'tabpaint']}, 13:06:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['a11yr', 13:06:28 INFO - 'ts_paint', 13:06:28 INFO - 'tpaint', 13:06:28 INFO - 'sessionrestore', 13:06:28 INFO - 'sessionrestore_no_auto_restore', 13:06:28 INFO - 'tabpaint']}, 13:06:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['a11yr', 13:06:28 INFO - 'ts_paint', 13:06:28 INFO - 'tpaint', 13:06:28 INFO - 'sessionrestore', 13:06:28 INFO - 'sessionrestore_no_auto_restore', 13:06:28 INFO - 'tabpaint']}, 13:06:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['tsvgx', 13:06:28 INFO - 'tsvgr_opacity', 13:06:28 INFO - 'tart', 13:06:28 INFO - 'tscrollx', 13:06:28 INFO - 'cart', 13:06:28 INFO - 'tsvg_static']}, 13:06:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:06:28 INFO - 'tsvgr_opacity', 13:06:28 INFO - 'tart', 13:06:28 INFO - 'tscrollx', 13:06:28 INFO - 'cart', 13:06:28 INFO - 'tsvg_static']}, 13:06:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'talos_options': ['--disable-e10s'], 13:06:28 INFO - 'tests': ['tp5o']}, 13:06:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:06:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'talos_options': ['--disable-e10s', 13:06:28 INFO - '--xperf_path', 13:06:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:06:28 INFO - 'tests': ['tp5n']}, 13:06:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:06:28 INFO - 'talos_options': ['--xperf_path', 13:06:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:06:28 INFO - 'tests': ['tp5n']}}, 13:06:28 INFO - 'talos.zip': {'path': '', 13:06:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:06:28 INFO - Running post-action listener: _resource_record_post_action 13:06:28 INFO - [mozharness: 2017-01-07 21:06:28.763265Z] Finished populate-webroot step (success) 13:06:28 INFO - [mozharness: 2017-01-07 21:06:28.763358Z] Running create-virtualenv step. 13:06:28 INFO - Running pre-action listener: _resource_record_pre_action 13:06:28 INFO - Running main action method: create_virtualenv 13:06:28 INFO - Creating virtualenv /builds/slave/test/build/venv 13:06:28 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 13:06:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:06:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:06:28 INFO - Using real prefix '/usr' 13:06:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:06:29 INFO - Installing distribute.............................................................................................................................................................................................done. 13:06:31 INFO - Installing pip.................done. 13:06:31 INFO - Return code: 0 13:06:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:06:31 INFO - Reading from file tmpfile_stdout 13:06:31 INFO - Output received: 13:06:31 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) 13:06:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:06: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')]} 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06: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')]} 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:31 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 13:06:31 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 13:06:31 INFO - Using env: {'DISPLAY': ':0', 13:06:31 INFO - 'HOME': '/home/cltbld', 13:06:31 INFO - 'LANG': 'en_US.UTF-8', 13:06:31 INFO - 'LANGUAGE': 'en_US:en', 13:06:31 INFO - 'LOGNAME': 'cltbld', 13:06:31 INFO - 'MAIL': '/var/mail/cltbld', 13:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:31 INFO - 'MOZ_NO_REMOTE': '1', 13:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:31 INFO - 'NO_EM_RESTART': '1', 13:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:31 INFO - 'PWD': '/builds/slave/test', 13:06:31 INFO - 'SHELL': '/bin/bash', 13:06:31 INFO - 'SHLVL': '1', 13:06:31 INFO - 'TERM': 'linux', 13:06:31 INFO - 'TMOUT': '86400', 13:06:31 INFO - 'USER': 'cltbld', 13:06:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:31 INFO - '_': '/tools/buildbot/bin/python'} 13:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:31 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 13:06:31 INFO - Cleaning up... 13:06:31 INFO - Return code: 0 13:06:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:06: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')]} 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06: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')]} 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:31 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 13:06:31 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 13:06:31 INFO - Using env: {'DISPLAY': ':0', 13:06:31 INFO - 'HOME': '/home/cltbld', 13:06:31 INFO - 'LANG': 'en_US.UTF-8', 13:06:31 INFO - 'LANGUAGE': 'en_US:en', 13:06:31 INFO - 'LOGNAME': 'cltbld', 13:06:31 INFO - 'MAIL': '/var/mail/cltbld', 13:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:31 INFO - 'MOZ_NO_REMOTE': '1', 13:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:31 INFO - 'NO_EM_RESTART': '1', 13:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:31 INFO - 'PWD': '/builds/slave/test', 13:06:31 INFO - 'SHELL': '/bin/bash', 13:06:31 INFO - 'SHLVL': '1', 13:06:31 INFO - 'TERM': 'linux', 13:06:31 INFO - 'TMOUT': '86400', 13:06:31 INFO - 'USER': 'cltbld', 13:06:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:31 INFO - '_': '/tools/buildbot/bin/python'} 13:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:31 INFO - Downloading/unpacking psutil>=3.1.1 13:06:31 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 13:06:31 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 13:06:31 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 13:06:31 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 13:06:31 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 13:06:31 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 13:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:06:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:06:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:06:34 INFO - Installing collected packages: psutil 13:06:34 INFO - Running setup.py install for psutil 13:06:34 INFO - building 'psutil._psutil_linux' extension 13:06:34 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 13:06:34 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 13:06:34 INFO - building 'psutil._psutil_posix' extension 13:06:34 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 13:06:34 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 13:06:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:06:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:06:34 INFO - Successfully installed psutil 13:06:34 INFO - Cleaning up... 13:06:34 INFO - Return code: 0 13:06:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:06:34 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')]} 13:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:34 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')]} 13:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:34 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:34 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 13:06:34 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 13:06:34 INFO - Using env: {'DISPLAY': ':0', 13:06:34 INFO - 'HOME': '/home/cltbld', 13:06:34 INFO - 'LANG': 'en_US.UTF-8', 13:06:34 INFO - 'LANGUAGE': 'en_US:en', 13:06:34 INFO - 'LOGNAME': 'cltbld', 13:06:34 INFO - 'MAIL': '/var/mail/cltbld', 13:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:34 INFO - 'MOZ_NO_REMOTE': '1', 13:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:34 INFO - 'NO_EM_RESTART': '1', 13:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:34 INFO - 'PWD': '/builds/slave/test', 13:06:34 INFO - 'SHELL': '/bin/bash', 13:06:34 INFO - 'SHLVL': '1', 13:06:34 INFO - 'TERM': 'linux', 13:06:34 INFO - 'TMOUT': '86400', 13:06:34 INFO - 'USER': 'cltbld', 13:06:34 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:34 INFO - '_': '/tools/buildbot/bin/python'} 13:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:06:34 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 13:06:34 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 13:06:34 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 13:06:34 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 13:06:34 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 13:06:34 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 13:06:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:06:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:06:36 INFO - Installing collected packages: mozsystemmonitor 13:06:36 INFO - Running setup.py install for mozsystemmonitor 13:06:37 INFO - Successfully installed mozsystemmonitor 13:06:37 INFO - Cleaning up... 13:06:37 INFO - Return code: 0 13:06:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:06:37 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')]} 13:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:37 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')]} 13:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:37 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:37 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 13:06:37 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 13:06:37 INFO - Using env: {'DISPLAY': ':0', 13:06:37 INFO - 'HOME': '/home/cltbld', 13:06:37 INFO - 'LANG': 'en_US.UTF-8', 13:06:37 INFO - 'LANGUAGE': 'en_US:en', 13:06:37 INFO - 'LOGNAME': 'cltbld', 13:06:37 INFO - 'MAIL': '/var/mail/cltbld', 13:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:37 INFO - 'MOZ_NO_REMOTE': '1', 13:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:37 INFO - 'NO_EM_RESTART': '1', 13:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:37 INFO - 'PWD': '/builds/slave/test', 13:06:37 INFO - 'SHELL': '/bin/bash', 13:06:37 INFO - 'SHLVL': '1', 13:06:37 INFO - 'TERM': 'linux', 13:06:37 INFO - 'TMOUT': '86400', 13:06:37 INFO - 'USER': 'cltbld', 13:06:37 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:37 INFO - '_': '/tools/buildbot/bin/python'} 13:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:37 INFO - Downloading/unpacking jsonschema==2.5.1 13:06:37 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 13:06:37 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 13:06:37 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 13:06:37 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 13:06:37 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 13:06:37 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 13:06:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:06:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 INFO - Downloading functools32-3.2.3-2.tar.gz 13:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:06:39 INFO - warning: no files found matching '*.txt' 13:06:39 INFO - no previously-included directories found matching 'build' 13:06:39 INFO - no previously-included directories found matching 'dist' 13:06:39 INFO - no previously-included directories found matching '.git*' 13:06:39 INFO - Installing collected packages: jsonschema, functools32 13:06:39 INFO - Running setup.py install for functools32 13:06:39 INFO - warning: no files found matching '*.txt' 13:06:39 INFO - no previously-included directories found matching 'build' 13:06:39 INFO - no previously-included directories found matching 'dist' 13:06:39 INFO - no previously-included directories found matching '.git*' 13:06:39 INFO - Successfully installed jsonschema functools32 13:06:39 INFO - Cleaning up... 13:06:39 INFO - Return code: 0 13:06:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:39 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:39 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 13:06:39 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 13:06:39 INFO - Using env: {'DISPLAY': ':0', 13:06:39 INFO - 'HOME': '/home/cltbld', 13:06:39 INFO - 'LANG': 'en_US.UTF-8', 13:06:39 INFO - 'LANGUAGE': 'en_US:en', 13:06:39 INFO - 'LOGNAME': 'cltbld', 13:06:39 INFO - 'MAIL': '/var/mail/cltbld', 13:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:39 INFO - 'MOZ_NO_REMOTE': '1', 13:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:39 INFO - 'NO_EM_RESTART': '1', 13:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:39 INFO - 'PWD': '/builds/slave/test', 13:06:39 INFO - 'SHELL': '/bin/bash', 13:06:39 INFO - 'SHLVL': '1', 13:06:39 INFO - 'TERM': 'linux', 13:06:39 INFO - 'TMOUT': '86400', 13:06:39 INFO - 'USER': 'cltbld', 13:06:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:39 INFO - '_': '/tools/buildbot/bin/python'} 13:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:06:40 INFO - Cleaning up... 13:06:40 INFO - Return code: 0 13:06:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:06:40 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')]} 13:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:40 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')]} 13:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:40 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:40 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 13:06:40 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 13:06:40 INFO - Using env: {'DISPLAY': ':0', 13:06:40 INFO - 'HOME': '/home/cltbld', 13:06:40 INFO - 'LANG': 'en_US.UTF-8', 13:06:40 INFO - 'LANGUAGE': 'en_US:en', 13:06:40 INFO - 'LOGNAME': 'cltbld', 13:06:40 INFO - 'MAIL': '/var/mail/cltbld', 13:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:40 INFO - 'MOZ_NO_REMOTE': '1', 13:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:40 INFO - 'NO_EM_RESTART': '1', 13:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:40 INFO - 'PWD': '/builds/slave/test', 13:06:40 INFO - 'SHELL': '/bin/bash', 13:06:40 INFO - 'SHLVL': '1', 13:06:40 INFO - 'TERM': 'linux', 13:06:40 INFO - 'TMOUT': '86400', 13:06:40 INFO - 'USER': 'cltbld', 13:06:40 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:40 INFO - '_': '/tools/buildbot/bin/python'} 13:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:40 INFO - Downloading/unpacking blobuploader==1.2.4 13:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:42 INFO - Downloading blobuploader-1.2.4.tar.gz 13:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:06:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:06:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 INFO - Downloading docopt-0.6.1.tar.gz 13:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:06:43 INFO - Installing collected packages: blobuploader, requests, docopt 13:06:43 INFO - Running setup.py install for blobuploader 13:06:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:06:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:06:43 INFO - Running setup.py install for requests 13:06:43 INFO - Running setup.py install for docopt 13:06:43 INFO - Successfully installed blobuploader requests docopt 13:06:43 INFO - Cleaning up... 13:06:43 INFO - Return code: 0 13:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:43 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')]} 13:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:43 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')]} 13:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:43 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:43 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 13:06:43 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 13:06:43 INFO - Using env: {'DISPLAY': ':0', 13:06:43 INFO - 'HOME': '/home/cltbld', 13:06:43 INFO - 'LANG': 'en_US.UTF-8', 13:06:43 INFO - 'LANGUAGE': 'en_US:en', 13:06:43 INFO - 'LOGNAME': 'cltbld', 13:06:43 INFO - 'MAIL': '/var/mail/cltbld', 13:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:43 INFO - 'MOZ_NO_REMOTE': '1', 13:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:43 INFO - 'NO_EM_RESTART': '1', 13:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:43 INFO - 'PWD': '/builds/slave/test', 13:06:43 INFO - 'SHELL': '/bin/bash', 13:06:43 INFO - 'SHLVL': '1', 13:06:43 INFO - 'TERM': 'linux', 13:06:43 INFO - 'TMOUT': '86400', 13:06:43 INFO - 'USER': 'cltbld', 13:06:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:43 INFO - '_': '/tools/buildbot/bin/python'} 13:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:43 INFO - Running setup.py (path:/tmp/pip-f48UPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:43 INFO - Running setup.py (path:/tmp/pip-EUMcDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:44 INFO - Running setup.py (path:/tmp/pip-3Sh04q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:44 INFO - Running setup.py (path:/tmp/pip-pWU3N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:44 INFO - Running setup.py (path:/tmp/pip-LfIDwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:44 INFO - Running setup.py (path:/tmp/pip-kAHby8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:44 INFO - Running setup.py (path:/tmp/pip-4E3Iat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:44 INFO - Running setup.py (path:/tmp/pip-ej9aUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:44 INFO - Running setup.py (path:/tmp/pip-_eBANH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:44 INFO - Running setup.py (path:/tmp/pip-wmaWqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:44 INFO - Running setup.py (path:/tmp/pip-rOKurI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:44 INFO - Running setup.py (path:/tmp/pip-TL2ZnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:44 INFO - Running setup.py (path:/tmp/pip-zWF2LO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:44 INFO - Running setup.py (path:/tmp/pip-lUMjMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:44 INFO - Running setup.py (path:/tmp/pip-vztK_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:45 INFO - Running setup.py (path:/tmp/pip-jwu4Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:45 INFO - Running setup.py (path:/tmp/pip-_1Bjfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:06:45 INFO - Running setup.py install for manifestparser 13:06:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozcrash 13:06:45 INFO - Running setup.py install for mozdebug 13:06:45 INFO - Running setup.py install for mozdevice 13:06:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozfile 13:06:45 INFO - Running setup.py install for mozhttpd 13:06:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozinfo 13:06:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozInstall 13:06:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozleak 13:06:46 INFO - Running setup.py install for mozlog 13:06:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Running setup.py install for moznetwork 13:06:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Running setup.py install for mozprocess 13:06:46 INFO - Running setup.py install for mozprofile 13:06:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Running setup.py install for mozrunner 13:06:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Running setup.py install for mozscreenshot 13:06:46 INFO - Running setup.py install for moztest 13:06:46 INFO - Running setup.py install for mozversion 13:06:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:06:46 INFO - Cleaning up... 13:06:46 INFO - Return code: 0 13:06:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:46 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:46 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 13:06:46 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 13:06:46 INFO - Using env: {'DISPLAY': ':0', 13:06:46 INFO - 'HOME': '/home/cltbld', 13:06:46 INFO - 'LANG': 'en_US.UTF-8', 13:06:46 INFO - 'LANGUAGE': 'en_US:en', 13:06:46 INFO - 'LOGNAME': 'cltbld', 13:06:46 INFO - 'MAIL': '/var/mail/cltbld', 13:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:46 INFO - 'MOZ_NO_REMOTE': '1', 13:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:46 INFO - 'NO_EM_RESTART': '1', 13:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:46 INFO - 'PWD': '/builds/slave/test', 13:06:46 INFO - 'SHELL': '/bin/bash', 13:06:47 INFO - 'SHLVL': '1', 13:06:47 INFO - 'TERM': 'linux', 13:06:47 INFO - 'TMOUT': '86400', 13:06:47 INFO - 'USER': 'cltbld', 13:06:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:47 INFO - '_': '/tools/buildbot/bin/python'} 13:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:47 INFO - Running setup.py (path:/tmp/pip-uFx6E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:47 INFO - Running setup.py (path:/tmp/pip-OlX4sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:47 INFO - Running setup.py (path:/tmp/pip-y3JiEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:47 INFO - Running setup.py (path:/tmp/pip-SeX7p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:47 INFO - Running setup.py (path:/tmp/pip-FH4rbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:47 INFO - Running setup.py (path:/tmp/pip-_Bl_qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:47 INFO - Running setup.py (path:/tmp/pip-Bju8pH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:47 INFO - Running setup.py (path:/tmp/pip-6MdqdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:47 INFO - Running setup.py (path:/tmp/pip-Dwuy0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:47 INFO - Running setup.py (path:/tmp/pip-VzctOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:47 INFO - Running setup.py (path:/tmp/pip-6EfVTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:48 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)) 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:48 INFO - Running setup.py (path:/tmp/pip-iRL5AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:48 INFO - Running setup.py (path:/tmp/pip-Crz1ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:48 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)) 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:48 INFO - Running setup.py (path:/tmp/pip-2oLDgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:48 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)) 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:48 INFO - Running setup.py (path:/tmp/pip-M2byPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:48 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)) 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:48 INFO - Running setup.py (path:/tmp/pip-nhKsLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:48 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)) 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:48 INFO - Running setup.py (path:/tmp/pip-wmMh05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:48 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)) 13:06:48 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)) 13:06:48 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)) 13:06:48 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)) 13:06:48 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)) 13:06:48 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)) 13:06:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:06:48 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 13:06:48 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 13:06:48 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 13:06:48 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 13:06:48 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 13:06:48 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 13:06:50 INFO - Downloading blessings-1.6.tar.gz 13:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:06:50 INFO - Installing collected packages: blessings 13:06:50 INFO - Running setup.py install for blessings 13:06:50 INFO - Successfully installed blessings 13:06:50 INFO - Cleaning up... 13:06:50 INFO - Return code: 0 13:06:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:51 INFO - Reading from file tmpfile_stdout 13:06:51 INFO - Current package versions: 13:06:51 INFO - argparse == 1.2.1 13:06:51 INFO - blessings == 1.6 13:06:51 INFO - blobuploader == 1.2.4 13:06:51 INFO - docopt == 0.6.1 13:06:51 INFO - functools32 == 3.2.3-2 13:06:51 INFO - jsonschema == 2.5.1 13:06:51 INFO - manifestparser == 1.1 13:06:51 INFO - mozInstall == 1.12 13:06:51 INFO - mozcrash == 1.0 13:06:51 INFO - mozdebug == 0.1 13:06:51 INFO - mozdevice == 0.49 13:06:51 INFO - mozfile == 1.2 13:06:51 INFO - mozhttpd == 0.7 13:06:51 INFO - mozinfo == 0.9 13:06:51 INFO - mozleak == 0.1 13:06:51 INFO - mozlog == 3.4 13:06:51 INFO - moznetwork == 0.27 13:06:51 INFO - mozprocess == 0.24 13:06:51 INFO - mozprofile == 0.28 13:06:51 INFO - mozrunner == 6.13 13:06:51 INFO - mozscreenshot == 0.1 13:06:51 INFO - mozsystemmonitor == 0.3 13:06:51 INFO - moztest == 0.8 13:06:51 INFO - mozversion == 1.4 13:06:51 INFO - psutil == 3.1.1 13:06:51 INFO - requests == 1.2.3 13:06:51 INFO - wsgiref == 0.1.2 13:06:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:51 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:51 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 13:06:51 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 13:06:51 INFO - Using env: {'DISPLAY': ':0', 13:06:51 INFO - 'HOME': '/home/cltbld', 13:06:51 INFO - 'LANG': 'en_US.UTF-8', 13:06:51 INFO - 'LANGUAGE': 'en_US:en', 13:06:51 INFO - 'LOGNAME': 'cltbld', 13:06:51 INFO - 'MAIL': '/var/mail/cltbld', 13:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:51 INFO - 'MOZ_NO_REMOTE': '1', 13:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:51 INFO - 'NO_EM_RESTART': '1', 13:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:51 INFO - 'PWD': '/builds/slave/test', 13:06:51 INFO - 'SHELL': '/bin/bash', 13:06:51 INFO - 'SHLVL': '1', 13:06:51 INFO - 'TERM': 'linux', 13:06:51 INFO - 'TMOUT': '86400', 13:06:51 INFO - 'USER': 'cltbld', 13:06:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:51 INFO - '_': '/tools/buildbot/bin/python'} 13:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 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)) 13:06:51 INFO - Cleaning up... 13:06:51 INFO - Return code: 0 13:06:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:51 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 0x22d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2642630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', '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 13:06:51 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 13:06:51 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 13:06:51 INFO - Using env: {'DISPLAY': ':0', 13:06:51 INFO - 'HOME': '/home/cltbld', 13:06:51 INFO - 'LANG': 'en_US.UTF-8', 13:06:51 INFO - 'LANGUAGE': 'en_US:en', 13:06:51 INFO - 'LOGNAME': 'cltbld', 13:06:51 INFO - 'MAIL': '/var/mail/cltbld', 13:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:51 INFO - 'MOZ_NO_REMOTE': '1', 13:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:51 INFO - 'NO_EM_RESTART': '1', 13:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:51 INFO - 'PWD': '/builds/slave/test', 13:06:51 INFO - 'SHELL': '/bin/bash', 13:06:51 INFO - 'SHLVL': '1', 13:06:51 INFO - 'TERM': 'linux', 13:06:51 INFO - 'TMOUT': '86400', 13:06:51 INFO - 'USER': 'cltbld', 13:06:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:51 INFO - '_': '/tools/buildbot/bin/python'} 13:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:06:51 INFO - Cleaning up... 13:06:51 INFO - Return code: 0 13:06:51 INFO - Running post-action listener: _resource_record_post_action 13:06:51 INFO - Running post-action listener: _start_resource_monitoring 13:06:51 INFO - Starting resource monitoring. 13:06:51 INFO - [mozharness: 2017-01-07 21:06:51.568221Z] Finished create-virtualenv step (success) 13:06:51 INFO - [mozharness: 2017-01-07 21:06:51.568805Z] Running install step. 13:06:51 INFO - Running pre-action listener: _resource_record_pre_action 13:06:51 INFO - Running main action method: install 13:06:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:51 INFO - Reading from file tmpfile_stdout 13:06:51 INFO - Detecting whether we're running mozinstall >=1.0... 13:06:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:06:51 INFO - Reading from file tmpfile_stdout 13:06:51 INFO - Output received: 13:06:51 INFO - Usage: mozinstall [options] installer 13:06:51 INFO - Options: 13:06:51 INFO - -h, --help show this help message and exit 13:06:51 INFO - -d DEST, --destination=DEST 13:06:51 INFO - Directory to install application into. [default: 13:06:51 INFO - "/builds/slave/test"] 13:06:51 INFO - --app=APP Application being installed. [default: firefox] 13:06:51 INFO - mkdir: /builds/slave/test/build/application 13:06:51 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'] 13:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:07:07 INFO - Reading from file tmpfile_stdout 13:07:07 INFO - Output received: 13:07:07 INFO - /builds/slave/test/build/application/firefox/firefox 13:07:07 INFO - Running post-action listener: _resource_record_post_action 13:07:07 INFO - [mozharness: 2017-01-07 21:07:07.990116Z] Finished install step (success) 13:07:07 INFO - [mozharness: 2017-01-07 21:07:07.990390Z] Running run-tests step. 13:07:07 INFO - Running pre-action listener: _resource_record_pre_action 13:07:07 INFO - Running main action method: run_tests 13:07:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:07:07 INFO - Python 2.7.3 13:07:07 INFO - Return code: 0 13:07:07 INFO - grabbing minidump binary from tooltool 13:07:07 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')]} 13:07:07 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 0x26cb850>, '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 0x26406d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2640b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:07:07 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 13:07:07 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 13:07:07 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 13:07:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:07:08 INFO - Return code: 0 13:07:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:07:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:07:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:07:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:07:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 13:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 13:07:08 INFO - Using env: {'DISPLAY': ':0', 13:07:08 INFO - 'HOME': '/home/cltbld', 13:07:08 INFO - 'LANG': 'en_US.UTF-8', 13:07:08 INFO - 'LANGUAGE': 'en_US:en', 13:07:08 INFO - 'LOGNAME': 'cltbld', 13:07:08 INFO - 'MAIL': '/var/mail/cltbld', 13:07:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:07:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:08 INFO - 'MOZ_NO_REMOTE': '1', 13:07:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:07:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:08 INFO - 'NO_EM_RESTART': '1', 13:07:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:08 INFO - 'PWD': '/builds/slave/test', 13:07:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:07:08 INFO - 'SHELL': '/bin/bash', 13:07:08 INFO - 'SHLVL': '1', 13:07:08 INFO - 'TERM': 'linux', 13:07:08 INFO - 'TMOUT': '86400', 13:07:08 INFO - 'USER': 'cltbld', 13:07:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460', 13:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:08 INFO - '_': '/tools/buildbot/bin/python'} 13:07:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 13:07:08 INFO - mozversion application_buildid: 20170107203201 13:07:08 INFO - mozversion application_changeset: f40258de5ee269f22833bdcc36dff621814349cd 13:07:08 INFO - mozversion application_display_name: Nightly 13:07:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:07:08 INFO - mozversion application_name: Firefox 13:07:08 INFO - mozversion application_remotingname: firefox 13:07:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:07:08 INFO - mozversion application_vendor: Mozilla 13:07:08 INFO - mozversion application_version: 53.0a1 13:07:08 INFO - mozversion platform_buildid: 20170107203201 13:07:08 INFO - mozversion platform_changeset: f40258de5ee269f22833bdcc36dff621814349cd 13:07:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:07:08 INFO - mozversion platform_version: 53.0a1 13:07:08 INFO - using testdate: 1483823228 13:07:08 INFO - actual date: 1483823228 13:07:08 INFO - starting webserver on 'localhost:55396' 13:07:08 INFO - SUITE-START | Running 2 tests 13:07:08 INFO - TEST-START | dromaeo_css 13:07:08 INFO - operating with platform_type : linux_ 13:07:08 INFO - Initialising browser for dromaeo_css test... 13:07:08 INFO - TEST-INFO | started process 28094 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2qzKRZ/profile http://localhost:55396/getInfo.html) 13:07:08 INFO - PROCESS | 28094 | 1483823228787 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 13:07:11 INFO - PROCESS | 28094 | __metrics Screen width/height:1600/1200 13:07:11 INFO - PROCESS | 28094 | colorDepth:24 13:07:11 INFO - PROCESS | 28094 | Browser inner width/height: 1024/697 13:07:11 INFO - PROCESS | 28094 | __metrics 13:07:12 INFO - PROCESS | 28094 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:07:12 INFO - PROCESS | 28094 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:07:13 INFO - PROCESS | 28094 | console.error: PushService: 13:07:13 INFO - PROCESS | 28094 | stateChangeProcessEnqueue: Error transitioning state 13:07:13 INFO - PROCESS | 28094 | UnknownError 13:07:13 INFO - TEST-INFO | 28094: exit 0 13:07:13 INFO - Browser initialized. 13:07:13 INFO - Running cycle 1/1 for dromaeo_css test... 13:07:13 INFO - TEST-INFO | started process 28177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2qzKRZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:25 INFO - PROCESS | 28177 | RSS: Main: 152481792 13:07:25 INFO - PROCESS | 28177 | 13:10:30 INFO - PROCESS | 28177 | Cycle 1(1): loaded http://localhost:55396/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55396/tests/dromaeo/cssquery-ext.html) 13:10:32 INFO - PROCESS | 28177 | RSS: Main: 319819776 13:10:32 INFO - PROCESS | 28177 | 13:12:14 INFO - PROCESS | 28177 | ************************* 13:12:14 INFO - PROCESS | 28177 | A coding exception was thrown and uncaught in a Task. 13:12:14 INFO - PROCESS | 28177 | 13:12:14 INFO - PROCESS | 28177 | Full message: ReferenceError: fetch is not defined 13:12:14 INFO - PROCESS | 28177 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 13:12:14 INFO - PROCESS | 28177 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 13:12:14 INFO - PROCESS | 28177 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 13:12:14 INFO - PROCESS | 28177 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 13:12:14 INFO - PROCESS | 28177 | TaskImpl@resource://gre/modules/Task.jsm:277:3 13:12:14 INFO - PROCESS | 28177 | asyncFunction@resource://gre/modules/Task.jsm:252:14 13:12:14 INFO - PROCESS | 28177 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 13:12:14 INFO - PROCESS | 28177 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 13:12:14 INFO - PROCESS | 28177 | TaskImpl@resource://gre/modules/Task.jsm:277:3 13:12:14 INFO - PROCESS | 28177 | asyncFunction@resource://gre/modules/Task.jsm:252:14 13:12:14 INFO - PROCESS | 28177 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 13:12:14 INFO - PROCESS | 28177 | 13:12:14 INFO - PROCESS | 28177 | ************************* 13:13:35 INFO - PROCESS | 28177 | Cycle 1(1): loaded http://localhost:55396/tests/dromaeo/cssquery-ext.html (next: http://localhost:55396/tests/dromaeo/cssquery-jquery.html) 13:13:35 INFO - PROCESS | 28177 | RSS: Main: 247185408 13:13:35 INFO - PROCESS | 28177 | 13:16:37 INFO - PROCESS | 28177 | Cycle 1(1): loaded http://localhost:55396/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55396/tests/dromaeo/cssquery-mootools.html) 13:16:38 INFO - PROCESS | 28177 | RSS: Main: 187887616 13:16:38 INFO - PROCESS | 28177 | 13:19:40 INFO - PROCESS | 28177 | Cycle 1(1): loaded http://localhost:55396/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55396/tests/dromaeo/cssquery-prototype.html) 13:19:41 INFO - PROCESS | 28177 | RSS: Main: 185061376 13:19:41 INFO - PROCESS | 28177 | 13:22:44 INFO - PROCESS | 28177 | Cycle 1(1): loaded http://localhost:55396/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55396/tests/dromaeo/cssquery-yui.html) 13:22:45 INFO - PROCESS | 28177 | RSS: Main: 184868864 13:22:45 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | Cycle 1(1): loaded http://localhost:55396/tests/dromaeo/cssquery-yui.html (next: http://localhost:55396/tests/dromaeo/cssquery-dojo.html) 13:25:48 INFO - PROCESS | 28177 | RSS: Main: 292864000 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | __start_tp_report 13:25:48 INFO - PROCESS | 28177 | _x_x_mozilla_page_load 13:25:48 INFO - PROCESS | 28177 | _x_x_mozilla_page_load_details 13:25:48 INFO - PROCESS | 28177 | |i|pagename|runs| 13:25:48 INFO - PROCESS | 28177 | |0;dojo.html;2596.4028273497092;2597.948041039178;2603.401217719925;2623.973760262398;2626.566616508276;4716.787744551495;4770.570648641621;4778.665493415458;4814.422269327677;4836.17784976554;4815.5906747926465;4828.855134345967;4839.8790030249265;4873.269009648555;4937.950620493794;4334.84828031019;4405.845795397162;4425.623821975136;4472.038511719979;4494.662900282476;4431.822727090913;4444.8444304449185;4483.775811209426;4508.346289787995;4531.207038768219;4429.933550996738;4524.5701658342405;4541.727496350213;4552.2943943688615;4580.496145424017;4375.9562404375865;4376.671749618759;4380.9780951095045;4390.473143222834;4407.052483715985;4376.343548467724;4401.845935392246;4432.53458386868;4438.800253988578;4442.111577684444;4769.356136921506;4778.116048531033;4788.856334309976;4796.208625576775;4806.182948898696;5079.6952182869145;5095.184770436749;5130.794768209267;5204.609654275944;5240.3187585614005;5163.199704045879;5168.302279192299;5173.663711858717;5201.427842937273;5215.426303106655;4808.567228949411;4816.181249187646;4821.349117869113;4827.203511420576;4861.173600487926;5222.973885130626;5251.921221181686;5283.9471605284225;5284.128118860422;5287.973560132252;5009.974950125226;5059.519345662156;5066.670666406671;5102.617303702237;5127.948720512822;5052.065367906949;5140.280360749498;5140.485951404829;5149.150390185578;5151.845444636667;4998.425181104147;5070.188769796889;5099.107656160157;5107.41264754558;5148.510891465305;5040.7479626019285;5132;5136.768845401966;5138.486151384906;5146.330976973044;4308.289132293199;4324.243257429937;4329.696921215547;4346.174226896879;4357.193919124972;3741.775493470346;3747.437884317374;3756.718246131551;3791.184895247535;3798.981005094957;4457.621102206349;4458.9298568343365;4462.642988560908;4465.464144302705;4504.256797628354;5102.668326558762;5153.381594208644;5161.303224064778;5189.792408303701;5216.21756736485;4473.2842745160615;4474.574915383034;4497.740632622871;4501.639868810487;4512.210363186429;4672.392588963413;4677.649176311722;4683.086798074411;4686.414198225222;4728.05438912219;4742.336072949721;4769.284607308931;4771.379720636295;4848.17581011236;4869.95130048695;5125.461826508163;5190.377154741455;5195.038917800068;5238.423773384849;5241.558571392898;4811.542903424169;4817.710937343771;4820.855374338776;4830.8792280193275;4886.535779101122;4983.327250821093;5021.598272138147;5103.081445339874;5109.20807274873;5149.356330458692;5109.182530795055;5139.434662187082;5151.742412879416;5162.251473536391;5162.6902385856965;4943.604511638989;4973.900521989613;4975.850724478271;4984.252362145708;4986.6010719143105;5029.471905450021;5070.492950705047;5074.7716352764955;5117.488251174853;5118.846434606968;5006.199008158677;5010.198368261061;5024.371953505812;5026.371703537058;5052.924206136984;4941.2835138905375;4966.6771659842;5097.592192624656;5102.387713474407;5108.795648174031;4907.0431265904235;4908.410990681141;4914.533119353656;4924.852254432372;4954.479779623088 13:25:48 INFO - PROCESS | 28177 | |1;ext.html;507.700456730529;522.6315447609438;525.61104782461;526.0688581211258;531.2270646209763;29547.704522954766;38520.22959540808;40105.19789604206;40347;40420.19159616813;40497.19005619886;40547;40968.38547421791;40975.18049639006;41068;7419.554826710388;7522.43581731371;7715.807104822368;7781.494202876823;7784.727534536292;7404.962975185116;9864.210863130933;10117.443540605263;10130.797384052314;10441.738956526071;10065.396076235413;10109.595616175344;10129.088382045615;10135.290529662927;10138.59445622178;10042.648507302245;10084.848727269096;10101.090901818798;10105.646302379419;10117;9883.505824708736;9960.352577082538;9961.601535938555;9967.700968970943;9967.850482242773;38997;39143;39242.21515569673;39248.41127383091;39341.80329098365;3710.350688629479;3748.981255093707;3783.7729736215915;3801.581825999138;3824.4072168813877;28623.85688071567;28702;28955.42089158226;29334.70665293341;29346.413071738658;3317.170707323169;3440.501127336547;3448.4654878493875;3610.4584312353095;3612.313660404515;231541;233135;233465.9999999983;233842;235190;54759.45240547623;55162;55210.723946380014;55269.723651381486;55270.17094743582;55533.16700249499;55777;55995.72002140045;56003.439965600635;56026.43973560212;11651.883481165249;11797.115216358636;11810.763784724257;11822.881771182178;11823.054155667525;8257.669693212205;8396.160383961676;8436.24073833358;8449.112843151504;8537.359698022674;15853.128077955824;15980.200989950456;16043.518694439186;16140.75788863168;16346.75479867803;6494.350564943562;6574.80275591733;6646.60408338455;6691.862383394737;6755.864882702318;813.1665043330515;819.3895547816915;825.7687847402736;828.7099515169642;846.2595229174473;27106.051288205213;28102.71897281001;28172.98464491343;28180.71819281781;28287.71712282892;971.6453494474466;976.1263669016332;998.6654470752379;1003.4130033930087;1083.9973024951887;14331.498397556035;14968;14999.100053996795;15145;15243.695126097638;315294;496248;506849;508022;508887;38408.42387364192;38818;38834;38866.805665971486;39023.60976390257;1185.4961641302411;1243.30996297056;1247.0584708545173;1251.4368534159482;1253.2417887178126;24904.875475622506;25111.372215694755;25495.6175657369;25859.353516162246;25928.22215333543;36290.8185459071;36664;36699.26601468009;36728.26543469062;36851.262974740894;299237;300948;302041;302196;302290;20624.27815026467;21148.682769758772;21347;21636.24273150432;21893.562128757654;32709.672903270664;35785.56857725662;35790;35940;36405.81797091103;30566.84716576403;30638.693613063584;30661.693383066777;30810.84594577013;32402.675973239966;30492.84753576218;30621.693783061884;30674.233144171576;31293.8435307822;31937.520937186426;29789;30152;30340.84829575838;30383.848080759453;30543.694563054974;26940;28183.71816281811;28241;28380.57429138606;28632;20554.486137846674;21643;21696.67454988145;21855.344339669835;21930.45173870666 13:25:48 INFO - PROCESS | 28177 | |2;jquery.html;359.5523573151427;371.37423441501073;377.6091745043879;384.076296506901;394.81641036917847;17008.319667213313;17182.96902185868;18451.354202602877;18466.630667386646;18504.444866654005;11580.710482237948;17589.296428142858;17915.820841791578;17937.820621793777;18314.358997435058;4599.126166028453;4758.643101767364;4770.976145119287;4775.044991001813;4800.111979283826;4659.098823037812;4688.343631891538;4696.976515117437;4739.31279964405;5097.847064588068;5136.922946155811;5163.406208286057;5165.483451654842;5168.53483186515;5173.146430838907;4707.764611769432;4990.226514890198;5135.435102138762;5148;5162.870928226786;4707.9171790488035;4715.198416269208;4718.882027949295;4725.432948046222;4730.526947305277;12316.322602256872;12492.312922789242;15336.846631533654;22208.444788880246;22504.324870253753;2259.8531095478743;2292.197730794209;2306.688597039395;2328.8136949044206;2350.917717879883;9278.860817087681;9291.628334866597;9368.4378937264;9678.75803104921;9838.90161098387;1620.6434584391416;1684.7255511756375;1706.3601149568913;1725.3184991928117;1726.381928341681;79210;79496;80204.59897700533;80950.59524702397;81204.18795811966;9893.406395616286;9914.652987145415;10105.29262951601;10219.591216351411;10321.380717156995;13731.244781536912;13765.449382024606;13767.173969561858;13853.722925541635;14164.504242351468;6729.360710732472;6761.797146085625;6959.512834101564;7006.334398232262;7024.754133605299;6337.397947195009;6421.8073457796345;6576.967115164393;6584.275729669733;6592.077109204715;4129.731567448106;4185;4211.347241177622;4402.207602631557;4420.9336859947125;1701.387500499819;1777.9111044447725;1789.4363275568155;1790.355472030068;1810.5383127302716;948.5826236455937;956.5456408206186;961.4904100826573;981.3179840011123;992.2607657295362;16867.662646746998;17089.572760681083;17811.73282400765;18114;18363.816361836212;962.7063745557532;967.6516454076527;970.5292932927524;985.3496692183124;987.3779518903045;9660.71017869465;9708.805823883482;9845.45849978258;9925.950370248103;10082.949585252027;471872;478376;485146;485156;490636;92229.53885230665;93457;93706;94076.52961735148;94469.5276523613;912.7124955638952;930.9208717258973;936.5879013234292;940.6660635474445;943.6694261091827;15220.086794792347;15286.235688215767;15919;16161.27274272684;16235.107069111313;33349.49975750414;35197;38957.6104238954;39671.60328396679;39726.66423390559;53029.46970530245;54222;54620;55765.44234557765;55857.603559911324;7565.356944659766;7634.351080158249;7821.129344107968;7947.086085100056;8151.959240203761;8616.741497755078;10903.829362604465;11710.590129345432;11712.355820429959;12010.159288849696;9494.525273736424;9588.856167157359;9869.506524673594;9915.55380007887;10108.898911010796;9611.327207095435;9844.950775246078;9927.602895884373;10020.298579099393;10067.29528932997;9447.622095116118;9786.31495795316;9854.605815767289;10087.193024558164;10390.324628899098;7323.816904577428;7387.372073373608;7568.243175682388;7617.238276172338;7817.140114587277;8796.692115775917;9917.859446163784;9932.404055756677;10181.134603558781;10292.228082893664 13:25:48 INFO - PROCESS | 28177 | |3;mootools.html;1165.190192815992;1311.724537847052;1321.4912258780369;1322.2198902647442;1334.0461569977467;6142.1708069410615;6528.510361722872;6779.593224406539;6871.312868713127;6903.5512691675;6688.264290928007;6761.188657361124;6918.1006469159065;6939;6943.166819981597;1813.2384398552574;1873.93441229557;2042.60169266993;2078.6466300728835;2079.9896000519975;1953.798413975402;2003.5191554027;2012.175008246612;2022.6662600670873;2024.6861736430876;2022.3427386099577;2051.077015343094;2069.1206237349143;2073.388350436625;2073.6682130859067;2068.1830676882623;2087.5198704297945;2091.4875855415444;2096.916123355064;2102.190656597206;2034.2574960139443;2052.13810199715;2074.6576814825535;2076.9169233230805;2077.169132347058;6646.368594983469;6776.491763117786;6835.384815366591;6869.278725733826;6873.656317184171;4151.8962025949295;4206.537280899099;4219;4224.598663126998;4276.102018576072;2693.8653067346554;2702.2433718558837;2754.5730411786203;2796.8321900686024;2848.017433985272;1036.2797855490437;1058.0847566854693;1102.2945314998408;1110.9388983605897;1117.6144230240554;8728.083551227093;8780.64877404903;8823.16179962909;8859.689910853154;8922.86615700765;3648.616895226016;3848.24959132972;3876.3797792353653;3907.374820028782;3988.3219852625125;7697.345725613273;7702;7715.4599178058115;7732.226777322223;7855.371570274365;4332.241857674894;4334.263175260214;4339.761313127745;4342.218400687906;4352.695311328177;3424.4520876660113;3545.6986156177013;3909.198614284065;3983.8605648802154;4041.474608300902;3346.9665303346655;3376.375370556455;3466.1854464201397;3483.825808709554;3498.5626796650417;1103.8178700514486;1110.8167152419921;1121.3608243301403;1123.2811000959393;1125.4035361258545;761.1323091675495;794.7417089445837;800.2637573432456;807.2613558593821;807.5719868469721;4283.635890949242;4406.184855801687;4423.933640995388;4869.488703686062;4917.459079501252;830.2901019628251;885.7608445719619;895.6059333893066;900.7162743735702;906.1844340093836;3037.726604605596;3102.720755132004;3131.264152924061;3141.591593092883;3157.636871759777;8020.919790802017;8125.146859579777;8137.715179968673;8140.430169888169;8150.510969341858;7976.361891048702;8037.196280372033;8061.395395345256;8062.879056814152;8072.475289106189;6185.443310102112;6325.936740632626;6413.102165696713;6422.197225346832;6446.645434501147;4676.275177347517;4710.175719249186;4765.8570242892765;4777.211760059551;4819.2530157825595;7118.359347658736;7182.928170718226;7374;7404.296591823768;7409.740659076906;7244.4566657501955;7272;7303.050603421521;7319.890201647086;7347.889781653386;5671.461211184931;5752.309722833287;6045;6049.213602231682;6073.969630151821;6326.3041065483685;6521.858674731998;6548.8035358939305;6632.071509988509;6635.104260924713;5922.5261979040715;5946.672932988728;5954.46409823118;6003.249593800775;6138.324784273637;5600.215969764155;5640.548756099584;5642.153676948326;5680.375158732453;5767.625104368202;5075.898482030412;5145.562627176582;5200.531952124327;5231.346081739783;5282.207668849704;3997;4001.639852413297;4073.7759423232005;4077.6941729369823;4183.53981062089;3947.2105578883766;4015.317396042738;4020.638142567183;4034.7780872052317;4035.2534781065597 13:25:48 INFO - PROCESS | 28177 | |4;prototype.html;339.22487116937805;343.8246494287913;344.0847346059479;348.6391584709826;351.84870505682557;4870.318155458239;4871.415430148379;4899.9020019599675;4911.778969946352;4918.975405122969;4568.428946381702;4828.951710482894;4844.563989240967;4845.836999120213;4867.221244600864;3756.69946404287;3792;3845.750026248285;3847.7306588538813;3855.248226595801;3797.449369841371;3833.71247156463;3844.4041173617993;3878.806059697018;3886.8639597614088;3677.4667673187373;3814.9809250953845;3822.655960963513;3838.500994870677;3851.326017946862;3800.8099595020276;3816.332141875175;3852.5569559500736;3854.498915141042;3883.961160388388;3457.4986626938953;3463.1169051891857;3477.3219222251673;3477.8608855645743;3482.9129271768156;4671.8364857229835;4690.5778479936625;4710.646701497367;4713.764311784397;4722.929156062662;4324.036293485828;4343.652507799368;4389.868303950887;4401.515833258339;4431.313146462304;5050.595952323842;5051.696898186121;5054.949450505522;5063.645544811865;5066.214736715815;3783.1109689223013;3791.545014598266;3792.4500947362753;3793.9620603793883;3803.2773772983046;5043.394792624908;5263;5276.5251127398715;5325.910971636442;5333.519983201491;3693.5013773140818;3703.0742314421395;3703.9074023149637;3714.1086139326367;3727.7576957497677;3737.588865224823;3749.250149970017;3761.360568703327;3772.641599048086;3783.905402364908;4268.658507319407;4282.892927676833;4299.935500967488;4319.049809042006;4323.935140972888;5014.674046186986;5027.874303142377;5032.421271553818;5072.51811077947;5117.974410128;1329.6144118205805;1334.873187047229;1335.5325636027312;1343.650650830791;1345.5761435147897;911.49867572836;916.4501299220415;933.1368484152132;954.7804005078871;954.9809003819885;4353.064091220403;4400.251957167289;4404.537523560044;4427.955720442755;4438.644908407384;5158.303629010035;5161.329027226437;5162.5095615916425;5163.638545301868;5186.247994040843;4311.137772445523;4424.80088396023;4426.712263702849;4441.178381999276;4452.933206001912;2665.8533780641865;2666.3867310518303;2666.85332306725;2670.359113812671;2689.4889970905856;4693.131770622445;4694.826293426643;4712.246040633482;4719.315699223594;4747.477777444478;5009.046471875971;5161.845144645667;5171.095058364771;5176.922346164811;5187.610929180327;4655.674102812838;4686.789094490756;4709.364235828188;4722.386089808303;4735.384400027974;5030.698158110525;5059.974700126476;5064.721440320818;5074.568661663652;5080.771365288645;4754.881127971828;4885.1206782779445;4976.975115124401;5019.272205530251;5034.521720436553;4643.071385722801;4713.29300604912;4715.3869996900185;4719.811207551659;4735.6211503078985;3807.428885667172;3810.314143454205;3819.217060502646;3829.1384438501113;3842.4639737772936;4726.409198850144;5083.745812709276;5095.668781529189;5106.361704786902;5110.437851836369;4831.815276446155;4835.162638197462;4841.927371089366;4870.31815545831;4899.583535399388;4831.685940413862;4872.439669437918;4875.68308059975;4890.413150421829;4920.40955085392;4613.446386433649;4748.430188377417;4759;4760.738159401128;4808.3027960946165;4885.047415753894;4906.582940450102;4928.21148616234;4939.185034469272;4982.3379815834605;4928.507149285043;4941.703497790144;4956.950430495793;4978.103941290602;4983.352164218773 13:25:48 INFO - PROCESS | 28177 | |5;yui.html;657.3722095398895;669.2470970158574;728.8141523911402;754.4266357568248;787.6652422720342;11932.224405413654;12813.679658008554;12845.614631561044;12898.806517902227;12962.824108093519;12648.430820613072;13542.119762215449;13842.584722458318;13854.099483533564;13872.722545549082;757.8257000889785;778.7313376884965;797.1669605262515;802.0415603353995;804.0712976512109;793.682526989206;795.4749865089043;795.6300320351036;795.7135431244748;795.8129839487716;821.0885916632533;822.5969275055239;823.0575990490885;823.9711610093648;824.1058451580043;819.2831272636444;819.2913130142443;819.9631016604267;820.8358328334327;824.4558591329724;594.04655527878;594.643214071558;594.8631814682604;595.5503594785908;598.7844376024627;12087.274763514217;12112.515499380013;12346.135770496068;12378.133530652858;12405.93797031018;10651.78696426075;10893.83659245112;11071.778564428667;11181.385023823686;11286.548538058469;9053.825094528034;9215.953920230357;9261.814763704755;9441.15029647335;9454.4800035998;3177.602799650044;3230.7415406767404;3237.465818140004;3266.395716792401;3272.640009598942;57718.7114064427;59155;59522;59870.40129598692;61476.69261653663;8623.568821558896;8655.610497527627;8746.475211487332;9450.480223587769;9597.472139032281;18865.52836179079;19204.327848525234;19212.039398030043;19214.615707686047;19237.903810480857;6291.213598300213;6372.681365931685;6443.903341449882;6463.57986730861;6468;3419.12812232879;3433.725301975886;3446.121239084068;3487;3490.3542844573835;2758.1311886755607;2809.8314101153996;2830.8584570771377;2836.574513822943;2837.2197645647198;486.25117319327836;493.9407271127488;504.1781895510368;506.7922151917696;510.06403250035856;607.441154138194;615.67061622032;617.6338952707761;619.5664026487677;630.9179806625109;7795.649195786204;8177.918220817715;8399.538025408661;8463.619137138721;8673.349498787617;547.887683024979;549.4203615186017;551.8068675963461;569.3225062175998;589.5872888977647;3861.942070868939;4102.856400025985;4105.117399759066;4156.8129434175535;4196.748195108242;65049;65653;66168.33831661622;66942.33057669459;68484.31515684779;57500.424995749505;58591.41408585945;58976.705116475;59555;59914.70042649846;48971;49710.25434618411;50047.74976125096;50508.49491505112;51042.48957510452;7685.308322251024;7936.127026027017;8022.157673444204;8298.751037468886;8339.416240863082;36834.8158259207;36949.445758314185;37282.44076338912;37942.62057379391;38210.80894595509;37121;44250;44546.77726611346;45073;46553.30170047384;8691;8858.684312498126;9017.549122543978;9081.364304498622;9100.453972761656;10404.323718958243;10466.127532812909;10472.476376181314;10919.945400273265;11278.887211127783;9978.201743860329;10327;10407.271490995558;10719.624813131502;10751.301165424222;9989.25080618971;9993.550290237103;10087.69736907894;10090.899091008996;10287.177025838064;8951.149640784115;9025.323100767337;9301.255899527887;9602.279829012712;9622.13400793932;8699.956500217459;8723.607437665194;8920.643174272956;8940.46357218569;9156.58795354224;9328.347015709107;9786.21710263163;9811.16605088547;9854.21166306708;9872.35829671069 13:25:48 INFO - PROCESS | 28177 | __end_tp_report 13:25:48 INFO - PROCESS | 28177 | __start_cc_report 13:25:48 INFO - PROCESS | 28177 | _x_x_mozilla_cycle_collect,1793 13:25:48 INFO - PROCESS | 28177 | __end_cc_report 13:25:48 INFO - PROCESS | 28177 | __startTimestamp1483824348360__endTimestamp 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | ------- Summary: start ------- 13:25:48 INFO - PROCESS | 28177 | Number of tests: 6 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | [#0] dojo.html Cycles:165 Average:4761.18 Median:4873.27 stddev:509.16 (10.4%) stddev-sans-first:481.57 13:25:48 INFO - PROCESS | 28177 | Values: 2596.4 2597.9 2603.4 2624.0 2626.6 4716.8 4770.6 4778.7 4814.4 4836.2 4815.6 4828.9 4839.9 4873.3 4938.0 4334.8 4405.8 4425.6 4472.0 4494.7 4431.8 4444.8 4483.8 4508.3 4531.2 4429.9 4524.6 4541.7 4552.3 4580.5 4376.0 4376.7 4381.0 4390.5 4407.1 4376.3 4401.8 4432.5 4438.8 4442.1 4769.4 4778.1 4788.9 4796.2 4806.2 5079.7 5095.2 5130.8 5204.6 5240.3 5163.2 5168.3 5173.7 5201.4 5215.4 4808.6 4816.2 4821.3 4827.2 4861.2 5223.0 5251.9 5283.9 5284.1 5288.0 5010.0 5059.5 5066.7 5102.6 5127.9 5052.1 5140.3 5140.5 5149.2 5151.8 4998.4 5070.2 5099.1 5107.4 5148.5 5040.7 5132.0 5136.8 5138.5 5146.3 4308.3 4324.2 4329.7 4346.2 4357.2 3741.8 3747.4 3756.7 3791.2 3799.0 4457.6 4458.9 4462.6 4465.5 4504.3 5102.7 5153.4 5161.3 5189.8 5216.2 4473.3 4474.6 4497.7 4501.6 4512.2 4672.4 4677.6 4683.1 4686.4 4728.1 4742.3 4769.3 4771.4 4848.2 4870.0 5125.5 5190.4 5195.0 5238.4 5241.6 4811.5 4817.7 4820.9 4830.9 4886.5 4983.3 5021.6 5103.1 5109.2 5149.4 5109.2 5139.4 5151.7 5162.3 5162.7 4943.6 4973.9 4975.9 4984.3 4986.6 5029.5 5070.5 5074.8 5117.5 5118.8 5006.2 5010.2 5024.4 5026.4 5052.9 4941.3 4966.7 5097.6 5102.4 5108.8 4907.0 4908.4 4914.5 4924.9 4954.5 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | [#1] ext.html Cycles:180 Average:47308.43 Median:32170.10 stddev:93294.51 (290.0%) stddev-sans-first:93490.05 13:25:48 INFO - PROCESS | 28177 | Values: 507.7 522.6 525.6 526.1 531.2 29547.7 38520.2 40105.2 40347.0 40420.2 40497.2 40547.0 40968.4 40975.2 41068.0 7419.6 7522.4 7715.8 7781.5 7784.7 7405.0 9864.2 10117.4 10130.8 10441.7 10065.4 10109.6 10129.1 10135.3 10138.6 10042.6 10084.8 10101.1 10105.6 10117.0 9883.5 9960.4 9961.6 9967.7 9967.9 38997.0 39143.0 39242.2 39248.4 39341.8 3710.4 3749.0 3783.8 3801.6 3824.4 28623.9 28702.0 28955.4 29334.7 29346.4 3317.2 3440.5 3448.5 3610.5 3612.3 231541.0 233135.0 233466.0 233842.0 235190.0 54759.5 55162.0 55210.7 55269.7 55270.2 55533.2 55777.0 55995.7 56003.4 56026.4 11651.9 11797.1 11810.8 11822.9 11823.1 8257.7 8396.2 8436.2 8449.1 8537.4 15853.1 15980.2 16043.5 16140.8 16346.8 6494.4 6574.8 6646.6 6691.9 6755.9 813.2 819.4 825.8 828.7 846.3 27106.1 28102.7 28173.0 28180.7 28287.7 971.6 976.1 998.7 1003.4 1084.0 14331.5 14968.0 14999.1 15145.0 15243.7 315294.0 496248.0 506849.0 508022.0 508887.0 38408.4 38818.0 38834.0 38866.8 39023.6 1185.5 1243.3 1247.1 1251.4 1253.2 24904.9 25111.4 25495.6 25859.4 25928.2 36290.8 36664.0 36699.3 36728.3 36851.3 299237.0 300948.0 302041.0 302196.0 302290.0 20624.3 21148.7 21347.0 21636.2 21893.6 32709.7 35785.6 35790.0 35940.0 36405.8 30566.8 30638.7 30661.7 30810.8 32402.7 30492.8 30621.7 30674.2 31293.8 31937.5 29789.0 30152.0 30340.8 30383.8 30543.7 26940.0 28183.7 28241.0 28380.6 28632.0 20554.5 21643.0 21696.7 21855.3 21930.5 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | [#2] jquery.html Cycles:180 Average:27591.23 Median:241588.06 stddev:79735.51 (33.0%) stddev-sans-first:79932.98 13:25:48 INFO - PROCESS | 28177 | Values: 359.6 371.4 377.6 384.1 394.8 17008.3 17183.0 18451.4 18466.6 18504.4 11580.7 17589.3 17915.8 17937.8 18314.4 4599.1 4758.6 4771.0 4775.0 4800.1 4659.1 4688.3 4697.0 4739.3 5097.8 5136.9 5163.4 5165.5 5168.5 5173.1 4707.8 4990.2 5135.4 5148.0 5162.9 4707.9 4715.2 4718.9 4725.4 4730.5 12316.3 12492.3 15336.8 22208.4 22504.3 2259.9 2292.2 2306.7 2328.8 2350.9 9278.9 9291.6 9368.4 9678.8 9838.9 1620.6 1684.7 1706.4 1725.3 1726.4 79210.0 79496.0 80204.6 80950.6 81204.2 9893.4 9914.7 10105.3 10219.6 10321.4 13731.2 13765.4 13767.2 13853.7 14164.5 6729.4 6761.8 6959.5 7006.3 7024.8 6337.4 6421.8 6577.0 6584.3 6592.1 4129.7 4185.0 4211.3 4402.2 4420.9 1701.4 1777.9 1789.4 1790.4 1810.5 948.6 956.5 961.5 981.3 992.3 16867.7 17089.6 17811.7 18114.0 18363.8 962.7 967.7 970.5 985.3 987.4 9660.7 9708.8 9845.5 9926.0 10082.9 471872.0 478376.0 485146.0 485156.0 490636.0 92229.5 93457.0 93706.0 94076.5 94469.5 912.7 930.9 936.6 940.7 943.7 15220.1 15286.2 15919.0 16161.3 16235.1 33349.5 35197.0 38957.6 39671.6 39726.7 53029.5 54222.0 54620.0 55765.4 55857.6 7565.4 7634.4 7821.1 7947.1 8152.0 8616.7 10903.8 11710.6 11712.4 12010.2 9494.5 9588.9 9869.5 9915.6 10108.9 9611.3 9845.0 9927.6 10020.3 10067.3 9447.6 9786.3 9854.6 10087.2 10390.3 7323.8 7387.4 7568.2 7617.2 7817.1 8796.7 9917.9 9932.4 10181.1 10292.2 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | [#3] mootools.html Cycles:180 Average:4487.08 Median:4771.53 stddev:2352.02 (49.3%) stddev-sans-first:2345.37 13:25:48 INFO - PROCESS | 28177 | Values: 1165.2 1311.7 1321.5 1322.2 1334.0 6142.2 6528.5 6779.6 6871.3 6903.6 6688.3 6761.2 6918.1 6939.0 6943.2 1813.2 1873.9 2042.6 2078.6 2080.0 1953.8 2003.5 2012.2 2022.7 2024.7 2022.3 2051.1 2069.1 2073.4 2073.7 2068.2 2087.5 2091.5 2096.9 2102.2 2034.3 2052.1 2074.7 2076.9 2077.2 6646.4 6776.5 6835.4 6869.3 6873.7 4151.9 4206.5 4219.0 4224.6 4276.1 2693.9 2702.2 2754.6 2796.8 2848.0 1036.3 1058.1 1102.3 1110.9 1117.6 8728.1 8780.6 8823.2 8859.7 8922.9 3648.6 3848.2 3876.4 3907.4 3988.3 7697.3 7702.0 7715.5 7732.2 7855.4 4332.2 4334.3 4339.8 4342.2 4352.7 3424.5 3545.7 3909.2 3983.9 4041.5 3347.0 3376.4 3466.2 3483.8 3498.6 1103.8 1110.8 1121.4 1123.3 1125.4 761.1 794.7 800.3 807.3 807.6 4283.6 4406.2 4423.9 4869.5 4917.5 830.3 885.8 895.6 900.7 906.2 3037.7 3102.7 3131.3 3141.6 3157.6 8020.9 8125.1 8137.7 8140.4 8150.5 7976.4 8037.2 8061.4 8062.9 8072.5 6185.4 6325.9 6413.1 6422.2 6446.6 4676.3 4710.2 4765.9 4777.2 4819.3 7118.4 7182.9 7374.0 7404.3 7409.7 7244.5 7272.0 7303.1 7319.9 7347.9 5671.5 5752.3 6045.0 6049.2 6074.0 6326.3 6521.9 6548.8 6632.1 6635.1 5922.5 5946.7 5954.5 6003.2 6138.3 5600.2 5640.5 5642.2 5680.4 5767.6 5075.9 5145.6 5200.5 5231.3 5282.2 3997.0 4001.6 4073.8 4077.7 4183.5 3947.2 4015.3 4020.6 4034.8 4035.3 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | [#4] prototype.html Cycles:180 Average:4172.28 Median:4711.45 stddev:1164.54 (24.7%) stddev-sans-first:1131.71 13:25:48 INFO - PROCESS | 28177 | Values: 339.2 343.8 344.1 348.6 351.8 4870.3 4871.4 4899.9 4911.8 4919.0 4568.4 4829.0 4844.6 4845.8 4867.2 3756.7 3792.0 3845.8 3847.7 3855.2 3797.4 3833.7 3844.4 3878.8 3886.9 3677.5 3815.0 3822.7 3838.5 3851.3 3800.8 3816.3 3852.6 3854.5 3884.0 3457.5 3463.1 3477.3 3477.9 3482.9 4671.8 4690.6 4710.6 4713.8 4722.9 4324.0 4343.7 4389.9 4401.5 4431.3 5050.6 5051.7 5054.9 5063.6 5066.2 3783.1 3791.5 3792.5 3794.0 3803.3 5043.4 5263.0 5276.5 5325.9 5333.5 3693.5 3703.1 3703.9 3714.1 3727.8 3737.6 3749.3 3761.4 3772.6 3783.9 4268.7 4282.9 4299.9 4319.0 4323.9 5014.7 5027.9 5032.4 5072.5 5118.0 1329.6 1334.9 1335.5 1343.7 1345.6 911.5 916.5 933.1 954.8 955.0 4353.1 4400.3 4404.5 4428.0 4438.6 5158.3 5161.3 5162.5 5163.6 5186.2 4311.1 4424.8 4426.7 4441.2 4452.9 2665.9 2666.4 2666.9 2670.4 2689.5 4693.1 4694.8 4712.2 4719.3 4747.5 5009.0 5161.8 5171.1 5176.9 5187.6 4655.7 4686.8 4709.4 4722.4 4735.4 5030.7 5060.0 5064.7 5074.6 5080.8 4754.9 4885.1 4977.0 5019.3 5034.5 4643.1 4713.3 4715.4 4719.8 4735.6 3807.4 3810.3 3819.2 3829.1 3842.5 4726.4 5083.7 5095.7 5106.4 5110.4 4831.8 4835.2 4841.9 4870.3 4899.6 4831.7 4872.4 4875.7 4890.4 4920.4 4613.4 4748.4 4759.0 4760.7 4808.3 4885.0 4906.6 4928.2 4939.2 4982.3 4928.5 4941.7 4957.0 4978.1 4983.4 13:25:48 INFO - PROCESS | 28177 | 13:25:48 INFO - PROCESS | 28177 | [#5] yui.html Cycles:180 Average:14266.13 Median:30058.78 stddev:18282.32 (60.8%) stddev-sans-first:18305.04 13:25:48 INFO - PROCESS | 28177 | Values: 657.4 669.2 728.8 754.4 787.7 11932.2 12813.7 12845.6 12898.8 12962.8 12648.4 13542.1 13842.6 13854.1 13872.7 757.8 778.7 797.2 802.0 804.1 793.7 795.5 795.6 795.7 795.8 821.1 822.6 823.1 824.0 824.1 819.3 819.3 820.0 820.8 824.5 594.0 594.6 594.9 595.6 598.8 12087.3 12112.5 12346.1 12378.1 12405.9 10651.8 10893.8 11071.8 11181.4 11286.5 9053.8 9216.0 9261.8 9441.2 9454.5 3177.6 3230.7 3237.5 3266.4 3272.6 57718.7 59155.0 59522.0 59870.4 61476.7 8623.6 8655.6 8746.5 9450.5 9597.5 18865.5 19204.3 19212.0 19214.6 19237.9 6291.2 6372.7 6443.9 6463.6 6468.0 3419.1 3433.7 3446.1 3487.0 3490.4 2758.1 2809.8 2830.9 2836.6 2837.2 486.3 493.9 504.2 506.8 510.1 607.4 615.7 617.6 619.6 630.9 7795.6 8177.9 8399.5 8463.6 8673.3 547.9 549.4 551.8 569.3 589.6 3861.9 4102.9 4105.1 4156.8 4196.7 65049.0 65653.0 66168.3 66942.3 68484.3 57500.4 58591.4 58976.7 59555.0 59914.7 48971.0 49710.3 50047.7 50508.5 51042.5 7685.3 7936.1 8022.2 8298.8 8339.4 36834.8 36949.4 37282.4 37942.6 38210.8 37121.0 44250.0 44546.8 45073.0 46553.3 8691.0 8858.7 9017.5 9081.4 9100.5 10404.3 10466.1 10472.5 10919.9 11278.9 9978.2 10327.0 10407.3 10719.6 10751.3 9989.3 9993.6 10087.7 10090.9 10287.2 8951.1 9025.3 9301.3 9602.3 9622.1 8700.0 8723.6 8920.6 8940.5 9156.6 9328.3 9786.2 9811.2 9854.2 9872.4 13:25:48 INFO - PROCESS | 28177 | -------- Summary: end -------- 13:25:48 INFO - PROCESS | 28177 | 13:25:49 INFO - TEST-INFO | 28177: exit 0 13:25:49 INFO - TEST-OK | dromaeo_css | took 1120937ms 13:25:49 INFO - TEST-START | kraken 13:25:49 INFO - operating with platform_type : linux_ 13:25:49 INFO - Initialising browser for kraken test... 13:25:49 INFO - TEST-INFO | started process 28487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpijb5pq/profile http://localhost:55396/getInfo.html) 13:25:49 INFO - PROCESS | 28487 | 1483824349691 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 13:25:52 INFO - PROCESS | 28487 | __metrics Screen width/height:1600/1200 13:25:52 INFO - PROCESS | 28487 | colorDepth:24 13:25:52 INFO - PROCESS | 28487 | Browser inner width/height: 1024/697 13:25:52 INFO - PROCESS | 28487 | __metrics 13:25:53 INFO - PROCESS | 28487 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:25:53 INFO - PROCESS | 28487 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:25:54 INFO - TEST-INFO | 28487: exit 0 13:25:54 INFO - Browser initialized. 13:25:54 INFO - Running cycle 1/1 for kraken test... 13:25:54 INFO - TEST-INFO | started process 28573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpijb5pq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:26:06 INFO - PROCESS | 28573 | RSS: Main: 151834624 13:26:06 INFO - PROCESS | 28573 | 13:26:09 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55396/tests/kraken/driver.html?testName=audio-beat-detection) 13:26:10 INFO - PROCESS | 28573 | RSS: Main: 205717504 13:26:10 INFO - PROCESS | 28573 | 13:26:13 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55396/tests/kraken/driver.html?testName=audio-dft) 13:26:13 INFO - PROCESS | 28573 | RSS: Main: 186003456 13:26:13 INFO - PROCESS | 28573 | 13:26:16 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55396/tests/kraken/driver.html?testName=audio-fft) 13:26:17 INFO - PROCESS | 28573 | RSS: Main: 268869632 13:26:17 INFO - PROCESS | 28573 | 13:26:19 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55396/tests/kraken/driver.html?testName=audio-oscillator) 13:26:20 INFO - PROCESS | 28573 | RSS: Main: 179478528 13:26:20 INFO - PROCESS | 28573 | 13:26:22 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55396/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:26:22 INFO - PROCESS | 28573 | RSS: Main: 183443456 13:26:22 INFO - PROCESS | 28573 | 13:26:26 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55396/tests/kraken/driver.html?testName=imaging-darkroom) 13:26:26 INFO - PROCESS | 28573 | RSS: Main: 222593024 13:26:26 INFO - PROCESS | 28573 | 13:26:30 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55396/tests/kraken/driver.html?testName=imaging-desaturate) 13:26:30 INFO - PROCESS | 28573 | RSS: Main: 216010752 13:26:30 INFO - PROCESS | 28573 | 13:26:33 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55396/tests/kraken/driver.html?testName=json-parse-financial) 13:26:34 INFO - PROCESS | 28573 | RSS: Main: 218005504 13:26:34 INFO - PROCESS | 28573 | 13:26:35 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55396/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:26:36 INFO - PROCESS | 28573 | RSS: Main: 181616640 13:26:36 INFO - PROCESS | 28573 | 13:26:38 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:26:39 INFO - PROCESS | 28573 | RSS: Main: 190062592 13:26:39 INFO - PROCESS | 28573 | 13:26:41 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:26:41 INFO - PROCESS | 28573 | RSS: Main: 198225920 13:26:41 INFO - PROCESS | 28573 | 13:26:44 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:26:45 INFO - PROCESS | 28573 | RSS: Main: 202010624 13:26:45 INFO - PROCESS | 28573 | 13:26:48 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:26:49 INFO - PROCESS | 28573 | RSS: Main: 202969088 13:26:49 INFO - PROCESS | 28573 | 13:26:50 INFO - PROCESS | 28573 | Cycle 1(1): loaded http://localhost:55396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55396/tests/kraken/driver.html?testName=ai-astar) 13:26:51 INFO - PROCESS | 28573 | RSS: Main: 254013440 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | __start_tp_report 13:26:51 INFO - PROCESS | 28573 | _x_x_mozilla_page_load 13:26:51 INFO - PROCESS | 28573 | _x_x_mozilla_page_load_details 13:26:51 INFO - PROCESS | 28573 | |i|pagename|runs| 13:26:51 INFO - PROCESS | 28573 | |0;ai-astar;105;116;115;114;115;115;114;116;113;114 13:26:51 INFO - PROCESS | 28573 | |1;audio-beat-detection;159;123;125;168;125;124;129;134;154;128 13:26:51 INFO - PROCESS | 28573 | |2;audio-dft;165;164;164;165;165;163;163;163;163;163 13:26:51 INFO - PROCESS | 28573 | |3;audio-fft;75;77;77;108;77;76;77;77;93;77 13:26:51 INFO - PROCESS | 28573 | |4;audio-oscillator;85;85;109;84;85;84;97;85;90;119 13:26:51 INFO - PROCESS | 28573 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 13:26:51 INFO - PROCESS | 28573 | |6;imaging-darkroom;150;149;150;149;152;150;149;149;151;150 13:26:51 INFO - PROCESS | 28573 | |7;imaging-desaturate;96;95;96;96;96;95;95;96;96;95 13:26:51 INFO - PROCESS | 28573 | |8;json-parse-financial;59;58;67;67;59;60;61;61;60;62 13:26:51 INFO - PROCESS | 28573 | |9;json-stringify-tinderbox;69;69;69;69;68;68;69;68;69;69 13:26:51 INFO - PROCESS | 28573 | |10;stanford-crypto-aes;85;85;86;86;85;93;85;85;85;85 13:26:51 INFO - PROCESS | 28573 | |11;stanford-crypto-ccm;144;146;136;146;133;134;132;129;215;132 13:26:51 INFO - PROCESS | 28573 | |12;stanford-crypto-pbkdf2;181;187;181;180;183;184;181;178;179;188 13:26:51 INFO - PROCESS | 28573 | |13;stanford-crypto-sha256-iterative;70;69;70;72;69;68;69;69;69;68 13:26:51 INFO - PROCESS | 28573 | __end_tp_report 13:26:51 INFO - PROCESS | 28573 | __start_cc_report 13:26:51 INFO - PROCESS | 28573 | _x_x_mozilla_cycle_collect,1856 13:26:51 INFO - PROCESS | 28573 | __end_cc_report 13:26:51 INFO - PROCESS | 28573 | __startTimestamp1483824411208__endTimestamp 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | ------- Summary: start ------- 13:26:51 INFO - PROCESS | 28573 | Number of tests: 14 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:3.20 (2.8%) stddev-sans-first:1.00 13:26:51 INFO - PROCESS | 28573 | Values: 105.0 116.0 115.0 114.0 115.0 115.0 114.0 116.0 113.0 114.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:131.50 stddev:16.80 (12.8%) stddev-sans-first:15.80 13:26:51 INFO - PROCESS | 28573 | Values: 159.0 123.0 125.0 168.0 125.0 124.0 129.0 134.0 154.0 128.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 13:26:51 INFO - PROCESS | 28573 | Values: 165.0 164.0 164.0 165.0 165.0 163.0 163.0 163.0 163.0 163.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#3] audio-fft Cycles:10 Average:81.40 Median:77.00 stddev:10.69 (13.9%) stddev-sans-first:11.08 13:26:51 INFO - PROCESS | 28573 | Values: 75.0 77.0 77.0 108.0 77.0 76.0 77.0 77.0 93.0 77.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:85.00 stddev:12.34 (14.5%) stddev-sans-first:12.80 13:26:51 INFO - PROCESS | 28573 | Values: 85.0 85.0 109.0 84.0 85.0 84.0 97.0 85.0 90.0 119.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 13:26:51 INFO - PROCESS | 28573 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 13:26:51 INFO - PROCESS | 28573 | Values: 150.0 149.0 150.0 149.0 152.0 150.0 149.0 149.0 151.0 150.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 13:26:51 INFO - PROCESS | 28573 | Values: 96.0 95.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.00 stddev:3.17 (5.2%) stddev-sans-first:3.24 13:26:51 INFO - PROCESS | 28573 | Values: 59.0 58.0 67.0 67.0 59.0 60.0 61.0 61.0 60.0 62.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 13:26:51 INFO - PROCESS | 28573 | Values: 69.0 69.0 69.0 69.0 68.0 68.0 69.0 68.0 69.0 69.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.00 stddev:2.49 (2.9%) stddev-sans-first:2.62 13:26:51 INFO - PROCESS | 28573 | Values: 85.0 85.0 86.0 86.0 85.0 93.0 85.0 85.0 85.0 85.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:140.00 stddev:25.48 (18.2%) stddev-sans-first:27.02 13:26:51 INFO - PROCESS | 28573 | Values: 144.0 146.0 136.0 146.0 133.0 134.0 132.0 129.0 215.0 132.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:182.00 stddev:3.29 (1.8%) stddev-sans-first:3.46 13:26:51 INFO - PROCESS | 28573 | Values: 181.0 187.0 181.0 180.0 183.0 184.0 181.0 178.0 179.0 188.0 13:26:51 INFO - PROCESS | 28573 | 13:26:51 INFO - PROCESS | 28573 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 13:26:51 INFO - PROCESS | 28573 | Values: 70.0 69.0 70.0 72.0 69.0 68.0 69.0 69.0 69.0 68.0 13:26:51 INFO - PROCESS | 28573 | -------- Summary: end -------- 13:26:51 INFO - PROCESS | 28573 | 13:26:52 INFO - TEST-INFO | 28573: exit 0 13:26:52 INFO - TEST-OK | kraken | took 63198ms 13:26:52 INFO - SUITE-END | took 1184s 13:26:52 INFO - Completed test suite (00:19:44) 13:26:52 INFO - javascript benchmark 13:26:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2596.4028273497092, 2597.948041039178, 2603.401217719925, 2623.973760262398, 2626.566616508276, 4716.787744551495, 4770.570648641621, 4778.665493415458, 4814.422269327677, 4836.17784976554, 4815.5906747926465, 4828.855134345967, 4839.8790030249265, 4873.269009648555, 4937.950620493794, 4334.84828031019, 4405.845795397162, 4425.623821975136, 4472.038511719979, 4494.662900282476, 4431.822727090913, 4444.8444304449185, 4483.775811209426, 4508.346289787995, 4531.207038768219, 4429.933550996738, 4524.5701658342405, 4541.727496350213, 4552.2943943688615, 4580.496145424017, 4375.9562404375865, 4376.671749618759, 4380.9780951095045, 4390.473143222834, 4407.052483715985, 4376.343548467724, 4401.845935392246, 4432.53458386868, 4438.800253988578, 4442.111577684444, 4769.356136921506, 4778.116048531033, 4788.856334309976, 4796.208625576775, 4806.182948898696, 5079.6952182869145, 5095.184770436749, 5130.794768209267, 5204.609654275944, 5240.3187585614005, 5163.199704045879, 5168.302279192299, 5173.663711858717, 5201.427842937273, 5215.426303106655, 4808.567228949411, 4816.181249187646, 4821.349117869113, 4827.203511420576, 4861.173600487926, 5222.973885130626, 5251.921221181686, 5283.9471605284225, 5284.128118860422, 5287.973560132252, 5009.974950125226, 5059.519345662156, 5066.670666406671, 5102.617303702237, 5127.948720512822, 5052.065367906949, 5140.280360749498, 5140.485951404829, 5149.150390185578, 5151.845444636667, 4998.425181104147, 5070.188769796889, 5099.107656160157, 5107.41264754558, 5148.510891465305, 5040.7479626019285, 5132.0, 5136.768845401966, 5138.486151384906, 5146.330976973044, 4308.289132293199, 4324.243257429937, 4329.696921215547, 4346.174226896879, 4357.193919124972, 3741.775493470346, 3747.437884317374, 3756.718246131551, 3791.184895247535, 3798.981005094957, 4457.621102206349, 4458.9298568343365, 4462.642988560908, 4465.464144302705, 4504.256797628354, 5102.668326558762, 5153.381594208644, 5161.303224064778, 5189.792408303701, 5216.21756736485, 4473.2842745160615, 4474.574915383034, 4497.740632622871, 4501.639868810487, 4512.210363186429, 4672.392588963413, 4677.649176311722, 4683.086798074411, 4686.414198225222, 4728.05438912219, 4742.336072949721, 4769.284607308931, 4771.379720636295, 4848.17581011236, 4869.95130048695, 5125.461826508163, 5190.377154741455, 5195.038917800068, 5238.423773384849, 5241.558571392898, 4811.542903424169, 4817.710937343771, 4820.855374338776, 4830.8792280193275, 4886.535779101122, 4983.327250821093, 5021.598272138147, 5103.081445339874, 5109.20807274873, 5149.356330458692, 5109.182530795055, 5139.434662187082, 5151.742412879416, 5162.251473536391, 5162.6902385856965, 4943.604511638989, 4973.900521989613, 4975.850724478271, 4984.252362145708, 4986.6010719143105, 5029.471905450021, 5070.492950705047, 5074.7716352764955, 5117.488251174853, 5118.846434606968, 5006.199008158677, 5010.198368261061, 5024.371953505812, 5026.371703537058, 5052.924206136984, 4941.2835138905375, 4966.6771659842, 5097.592192624656, 5102.387713474407, 5108.795648174031, 4907.0431265904235, 4908.410990681141, 4914.533119353656, 4924.852254432372, 4954.479779623088], "value": 4727.224257341879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.700456730529, 522.6315447609438, 525.61104782461, 526.0688581211258, 531.2270646209763, 29547.704522954766, 38520.22959540808, 40105.19789604206, 40347.0, 40420.19159616813, 40497.19005619886, 40547.0, 40968.38547421791, 40975.18049639006, 41068.0, 7419.554826710388, 7522.43581731371, 7715.807104822368, 7781.494202876823, 7784.727534536292, 7404.962975185116, 9864.210863130933, 10117.443540605263, 10130.797384052314, 10441.738956526071, 10065.396076235413, 10109.595616175344, 10129.088382045615, 10135.290529662927, 10138.59445622178, 10042.648507302245, 10084.848727269096, 10101.090901818798, 10105.646302379419, 10117.0, 9883.505824708736, 9960.352577082538, 9961.601535938555, 9967.700968970943, 9967.850482242773, 38997.0, 39143.0, 39242.21515569673, 39248.41127383091, 39341.80329098365, 3710.350688629479, 3748.981255093707, 3783.7729736215915, 3801.581825999138, 3824.4072168813877, 28623.85688071567, 28702.0, 28955.42089158226, 29334.70665293341, 29346.413071738658, 3317.170707323169, 3440.501127336547, 3448.4654878493875, 3610.4584312353095, 3612.313660404515, 231541.0, 233135.0, 233465.9999999983, 233842.0, 235190.0, 54759.45240547623, 55162.0, 55210.723946380014, 55269.723651381486, 55270.17094743582, 55533.16700249499, 55777.0, 55995.72002140045, 56003.439965600635, 56026.43973560212, 11651.883481165249, 11797.115216358636, 11810.763784724257, 11822.881771182178, 11823.054155667525, 8257.669693212205, 8396.160383961676, 8436.24073833358, 8449.112843151504, 8537.359698022674, 15853.128077955824, 15980.200989950456, 16043.518694439186, 16140.75788863168, 16346.75479867803, 6494.350564943562, 6574.80275591733, 6646.60408338455, 6691.862383394737, 6755.864882702318, 813.1665043330515, 819.3895547816915, 825.7687847402736, 828.7099515169642, 846.2595229174473, 27106.051288205213, 28102.71897281001, 28172.98464491343, 28180.71819281781, 28287.71712282892, 971.6453494474466, 976.1263669016332, 998.6654470752379, 1003.4130033930087, 1083.9973024951887, 14331.498397556035, 14968.0, 14999.100053996795, 15145.0, 15243.695126097638, 315294.0, 496248.0, 506849.0, 508022.0, 508887.0, 38408.42387364192, 38818.0, 38834.0, 38866.805665971486, 39023.60976390257, 1185.4961641302411, 1243.30996297056, 1247.0584708545173, 1251.4368534159482, 1253.2417887178126, 24904.875475622506, 25111.372215694755, 25495.6175657369, 25859.353516162246, 25928.22215333543, 36290.8185459071, 36664.0, 36699.26601468009, 36728.26543469062, 36851.262974740894, 299237.0, 300948.0, 302041.0, 302196.0, 302290.0, 20624.27815026467, 21148.682769758772, 21347.0, 21636.24273150432, 21893.562128757654, 32709.672903270664, 35785.56857725662, 35790.0, 35940.0, 36405.81797091103, 30566.84716576403, 30638.693613063584, 30661.693383066777, 30810.84594577013, 32402.675973239966, 30492.84753576218, 30621.693783061884, 30674.233144171576, 31293.8435307822, 31937.520937186426, 29789.0, 30152.0, 30340.84829575838, 30383.848080759453, 30543.694563054974, 26940.0, 28183.71816281811, 28241.0, 28380.57429138606, 28632.0, 20554.486137846674, 21643.0, 21696.67454988145, 21855.344339669835, 21930.45173870666], "value": 17002.30615909563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.5523573151427, 371.37423441501073, 377.6091745043879, 384.076296506901, 394.81641036917847, 17008.319667213313, 17182.96902185868, 18451.354202602877, 18466.630667386646, 18504.444866654005, 11580.710482237948, 17589.296428142858, 17915.820841791578, 17937.820621793777, 18314.358997435058, 4599.126166028453, 4758.643101767364, 4770.976145119287, 4775.044991001813, 4800.111979283826, 4659.098823037812, 4688.343631891538, 4696.976515117437, 4739.31279964405, 5097.847064588068, 5136.922946155811, 5163.406208286057, 5165.483451654842, 5168.53483186515, 5173.146430838907, 4707.764611769432, 4990.226514890198, 5135.435102138762, 5148.0, 5162.870928226786, 4707.9171790488035, 4715.198416269208, 4718.882027949295, 4725.432948046222, 4730.526947305277, 12316.322602256872, 12492.312922789242, 15336.846631533654, 22208.444788880246, 22504.324870253753, 2259.8531095478743, 2292.197730794209, 2306.688597039395, 2328.8136949044206, 2350.917717879883, 9278.860817087681, 9291.628334866597, 9368.4378937264, 9678.75803104921, 9838.90161098387, 1620.6434584391416, 1684.7255511756375, 1706.3601149568913, 1725.3184991928117, 1726.381928341681, 79210.0, 79496.0, 80204.59897700533, 80950.59524702397, 81204.18795811966, 9893.406395616286, 9914.652987145415, 10105.29262951601, 10219.591216351411, 10321.380717156995, 13731.244781536912, 13765.449382024606, 13767.173969561858, 13853.722925541635, 14164.504242351468, 6729.360710732472, 6761.797146085625, 6959.512834101564, 7006.334398232262, 7024.754133605299, 6337.397947195009, 6421.8073457796345, 6576.967115164393, 6584.275729669733, 6592.077109204715, 4129.731567448106, 4185.0, 4211.347241177622, 4402.207602631557, 4420.9336859947125, 1701.387500499819, 1777.9111044447725, 1789.4363275568155, 1790.355472030068, 1810.5383127302716, 948.5826236455937, 956.5456408206186, 961.4904100826573, 981.3179840011123, 992.2607657295362, 16867.662646746998, 17089.572760681083, 17811.73282400765, 18114.0, 18363.816361836212, 962.7063745557532, 967.6516454076527, 970.5292932927524, 985.3496692183124, 987.3779518903045, 9660.71017869465, 9708.805823883482, 9845.45849978258, 9925.950370248103, 10082.949585252027, 471872.0, 478376.0, 485146.0, 485156.0, 490636.0, 92229.53885230665, 93457.0, 93706.0, 94076.52961735148, 94469.5276523613, 912.7124955638952, 930.9208717258973, 936.5879013234292, 940.6660635474445, 943.6694261091827, 15220.086794792347, 15286.235688215767, 15919.0, 16161.27274272684, 16235.107069111313, 33349.49975750414, 35197.0, 38957.6104238954, 39671.60328396679, 39726.66423390559, 53029.46970530245, 54222.0, 54620.0, 55765.44234557765, 55857.603559911324, 7565.356944659766, 7634.351080158249, 7821.129344107968, 7947.086085100056, 8151.959240203761, 8616.741497755078, 10903.829362604465, 11710.590129345432, 11712.355820429959, 12010.159288849696, 9494.525273736424, 9588.856167157359, 9869.506524673594, 9915.55380007887, 10108.898911010796, 9611.327207095435, 9844.950775246078, 9927.602895884373, 10020.298579099393, 10067.29528932997, 9447.622095116118, 9786.31495795316, 9854.605815767289, 10087.193024558164, 10390.324628899098, 7323.816904577428, 7387.372073373608, 7568.243175682388, 7617.238276172338, 7817.140114587277, 8796.692115775917, 9917.859446163784, 9932.404055756677, 10181.134603558781, 10292.228082893664], "value": 8260.278757105929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1165.190192815992, 1311.724537847052, 1321.4912258780369, 1322.2198902647442, 1334.0461569977467, 6142.1708069410615, 6528.510361722872, 6779.593224406539, 6871.312868713127, 6903.5512691675, 6688.264290928007, 6761.188657361124, 6918.1006469159065, 6939.0, 6943.166819981597, 1813.2384398552574, 1873.93441229557, 2042.60169266993, 2078.6466300728835, 2079.9896000519975, 1953.798413975402, 2003.5191554027, 2012.175008246612, 2022.6662600670873, 2024.6861736430876, 2022.3427386099577, 2051.077015343094, 2069.1206237349143, 2073.388350436625, 2073.6682130859067, 2068.1830676882623, 2087.5198704297945, 2091.4875855415444, 2096.916123355064, 2102.190656597206, 2034.2574960139443, 2052.13810199715, 2074.6576814825535, 2076.9169233230805, 2077.169132347058, 6646.368594983469, 6776.491763117786, 6835.384815366591, 6869.278725733826, 6873.656317184171, 4151.8962025949295, 4206.537280899099, 4219.0, 4224.598663126998, 4276.102018576072, 2693.8653067346554, 2702.2433718558837, 2754.5730411786203, 2796.8321900686024, 2848.017433985272, 1036.2797855490437, 1058.0847566854693, 1102.2945314998408, 1110.9388983605897, 1117.6144230240554, 8728.083551227093, 8780.64877404903, 8823.16179962909, 8859.689910853154, 8922.86615700765, 3648.616895226016, 3848.24959132972, 3876.3797792353653, 3907.374820028782, 3988.3219852625125, 7697.345725613273, 7702.0, 7715.4599178058115, 7732.226777322223, 7855.371570274365, 4332.241857674894, 4334.263175260214, 4339.761313127745, 4342.218400687906, 4352.695311328177, 3424.4520876660113, 3545.6986156177013, 3909.198614284065, 3983.8605648802154, 4041.474608300902, 3346.9665303346655, 3376.375370556455, 3466.1854464201397, 3483.825808709554, 3498.5626796650417, 1103.8178700514486, 1110.8167152419921, 1121.3608243301403, 1123.2811000959393, 1125.4035361258545, 761.1323091675495, 794.7417089445837, 800.2637573432456, 807.2613558593821, 807.5719868469721, 4283.635890949242, 4406.184855801687, 4423.933640995388, 4869.488703686062, 4917.459079501252, 830.2901019628251, 885.7608445719619, 895.6059333893066, 900.7162743735702, 906.1844340093836, 3037.726604605596, 3102.720755132004, 3131.264152924061, 3141.591593092883, 3157.636871759777, 8020.919790802017, 8125.146859579777, 8137.715179968673, 8140.430169888169, 8150.510969341858, 7976.361891048702, 8037.196280372033, 8061.395395345256, 8062.879056814152, 8072.475289106189, 6185.443310102112, 6325.936740632626, 6413.102165696713, 6422.197225346832, 6446.645434501147, 4676.275177347517, 4710.175719249186, 4765.8570242892765, 4777.211760059551, 4819.2530157825595, 7118.359347658736, 7182.928170718226, 7374.0, 7404.296591823768, 7409.740659076906, 7244.4566657501955, 7272.0, 7303.050603421521, 7319.890201647086, 7347.889781653386, 5671.461211184931, 5752.309722833287, 6045.0, 6049.213602231682, 6073.969630151821, 6326.3041065483685, 6521.858674731998, 6548.8035358939305, 6632.071509988509, 6635.104260924713, 5922.5261979040715, 5946.672932988728, 5954.46409823118, 6003.249593800775, 6138.324784273637, 5600.215969764155, 5640.548756099584, 5642.153676948326, 5680.375158732453, 5767.625104368202, 5075.898482030412, 5145.562627176582, 5200.531952124327, 5231.346081739783, 5282.207668849704, 3997.0, 4001.639852413297, 4073.7759423232005, 4077.6941729369823, 4183.53981062089, 3947.2105578883766, 4015.317396042738, 4020.638142567183, 4034.7780872052317, 4035.2534781065597], "value": 3719.199385371059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.22487116937805, 343.8246494287913, 344.0847346059479, 348.6391584709826, 351.84870505682557, 4870.318155458239, 4871.415430148379, 4899.9020019599675, 4911.778969946352, 4918.975405122969, 4568.428946381702, 4828.951710482894, 4844.563989240967, 4845.836999120213, 4867.221244600864, 3756.69946404287, 3792.0, 3845.750026248285, 3847.7306588538813, 3855.248226595801, 3797.449369841371, 3833.71247156463, 3844.4041173617993, 3878.806059697018, 3886.8639597614088, 3677.4667673187373, 3814.9809250953845, 3822.655960963513, 3838.500994870677, 3851.326017946862, 3800.8099595020276, 3816.332141875175, 3852.5569559500736, 3854.498915141042, 3883.961160388388, 3457.4986626938953, 3463.1169051891857, 3477.3219222251673, 3477.8608855645743, 3482.9129271768156, 4671.8364857229835, 4690.5778479936625, 4710.646701497367, 4713.764311784397, 4722.929156062662, 4324.036293485828, 4343.652507799368, 4389.868303950887, 4401.515833258339, 4431.313146462304, 5050.595952323842, 5051.696898186121, 5054.949450505522, 5063.645544811865, 5066.214736715815, 3783.1109689223013, 3791.545014598266, 3792.4500947362753, 3793.9620603793883, 3803.2773772983046, 5043.394792624908, 5263.0, 5276.5251127398715, 5325.910971636442, 5333.519983201491, 3693.5013773140818, 3703.0742314421395, 3703.9074023149637, 3714.1086139326367, 3727.7576957497677, 3737.588865224823, 3749.250149970017, 3761.360568703327, 3772.641599048086, 3783.905402364908, 4268.658507319407, 4282.892927676833, 4299.935500967488, 4319.049809042006, 4323.935140972888, 5014.674046186986, 5027.874303142377, 5032.421271553818, 5072.51811077947, 5117.974410128, 1329.6144118205805, 1334.873187047229, 1335.5325636027312, 1343.650650830791, 1345.5761435147897, 911.49867572836, 916.4501299220415, 933.1368484152132, 954.7804005078871, 954.9809003819885, 4353.064091220403, 4400.251957167289, 4404.537523560044, 4427.955720442755, 4438.644908407384, 5158.303629010035, 5161.329027226437, 5162.5095615916425, 5163.638545301868, 5186.247994040843, 4311.137772445523, 4424.80088396023, 4426.712263702849, 4441.178381999276, 4452.933206001912, 2665.8533780641865, 2666.3867310518303, 2666.85332306725, 2670.359113812671, 2689.4889970905856, 4693.131770622445, 4694.826293426643, 4712.246040633482, 4719.315699223594, 4747.477777444478, 5009.046471875971, 5161.845144645667, 5171.095058364771, 5176.922346164811, 5187.610929180327, 4655.674102812838, 4686.789094490756, 4709.364235828188, 4722.386089808303, 4735.384400027974, 5030.698158110525, 5059.974700126476, 5064.721440320818, 5074.568661663652, 5080.771365288645, 4754.881127971828, 4885.1206782779445, 4976.975115124401, 5019.272205530251, 5034.521720436553, 4643.071385722801, 4713.29300604912, 4715.3869996900185, 4719.811207551659, 4735.6211503078985, 3807.428885667172, 3810.314143454205, 3819.217060502646, 3829.1384438501113, 3842.4639737772936, 4726.409198850144, 5083.745812709276, 5095.668781529189, 5106.361704786902, 5110.437851836369, 4831.815276446155, 4835.162638197462, 4841.927371089366, 4870.31815545831, 4899.583535399388, 4831.685940413862, 4872.439669437918, 4875.68308059975, 4890.413150421829, 4920.40955085392, 4613.446386433649, 4748.430188377417, 4759.0, 4760.738159401128, 4808.3027960946165, 4885.047415753894, 4906.582940450102, 4928.21148616234, 4939.185034469272, 4982.3379815834605, 4928.507149285043, 4941.703497790144, 4956.950430495793, 4978.103941290602, 4983.352164218773], "value": 3819.823623515105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.3722095398895, 669.2470970158574, 728.8141523911402, 754.4266357568248, 787.6652422720342, 11932.224405413654, 12813.679658008554, 12845.614631561044, 12898.806517902227, 12962.824108093519, 12648.430820613072, 13542.119762215449, 13842.584722458318, 13854.099483533564, 13872.722545549082, 757.8257000889785, 778.7313376884965, 797.1669605262515, 802.0415603353995, 804.0712976512109, 793.682526989206, 795.4749865089043, 795.6300320351036, 795.7135431244748, 795.8129839487716, 821.0885916632533, 822.5969275055239, 823.0575990490885, 823.9711610093648, 824.1058451580043, 819.2831272636444, 819.2913130142443, 819.9631016604267, 820.8358328334327, 824.4558591329724, 594.04655527878, 594.643214071558, 594.8631814682604, 595.5503594785908, 598.7844376024627, 12087.274763514217, 12112.515499380013, 12346.135770496068, 12378.133530652858, 12405.93797031018, 10651.78696426075, 10893.83659245112, 11071.778564428667, 11181.385023823686, 11286.548538058469, 9053.825094528034, 9215.953920230357, 9261.814763704755, 9441.15029647335, 9454.4800035998, 3177.602799650044, 3230.7415406767404, 3237.465818140004, 3266.395716792401, 3272.640009598942, 57718.7114064427, 59155.0, 59522.0, 59870.40129598692, 61476.69261653663, 8623.568821558896, 8655.610497527627, 8746.475211487332, 9450.480223587769, 9597.472139032281, 18865.52836179079, 19204.327848525234, 19212.039398030043, 19214.615707686047, 19237.903810480857, 6291.213598300213, 6372.681365931685, 6443.903341449882, 6463.57986730861, 6468.0, 3419.12812232879, 3433.725301975886, 3446.121239084068, 3487.0, 3490.3542844573835, 2758.1311886755607, 2809.8314101153996, 2830.8584570771377, 2836.574513822943, 2837.2197645647198, 486.25117319327836, 493.9407271127488, 504.1781895510368, 506.7922151917696, 510.06403250035856, 607.441154138194, 615.67061622032, 617.6338952707761, 619.5664026487677, 630.9179806625109, 7795.649195786204, 8177.918220817715, 8399.538025408661, 8463.619137138721, 8673.349498787617, 547.887683024979, 549.4203615186017, 551.8068675963461, 569.3225062175998, 589.5872888977647, 3861.942070868939, 4102.856400025985, 4105.117399759066, 4156.8129434175535, 4196.748195108242, 65049.0, 65653.0, 66168.33831661622, 66942.33057669459, 68484.31515684779, 57500.424995749505, 58591.41408585945, 58976.705116475, 59555.0, 59914.70042649846, 48971.0, 49710.25434618411, 50047.74976125096, 50508.49491505112, 51042.48957510452, 7685.308322251024, 7936.127026027017, 8022.157673444204, 8298.751037468886, 8339.416240863082, 36834.8158259207, 36949.445758314185, 37282.44076338912, 37942.62057379391, 38210.80894595509, 37121.0, 44250.0, 44546.77726611346, 45073.0, 46553.30170047384, 8691.0, 8858.684312498126, 9017.549122543978, 9081.364304498622, 9100.453972761656, 10404.323718958243, 10466.127532812909, 10472.476376181314, 10919.945400273265, 11278.887211127783, 9978.201743860329, 10327.0, 10407.271490995558, 10719.624813131502, 10751.301165424222, 9989.25080618971, 9993.550290237103, 10087.69736907894, 10090.899091008996, 10287.177025838064, 8951.149640784115, 9025.323100767337, 9301.255899527887, 9602.279829012712, 9622.13400793932, 8699.956500217459, 8723.607437665194, 8920.643174272956, 8940.46357218569, 9156.58795354224, 9328.347015709107, 9786.21710263163, 9811.16605088547, 9854.21166306708, 9872.35829671069], "value": 5996.253192445329, "unit": "score"}], "name": "dromaeo_css", "value": 6195.67974375218, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 114.0, 115.0, 115.0, 114.0, 116.0, 113.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 123.0, 125.0, 168.0, 125.0, 124.0, 129.0, 134.0, 154.0, 128.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 165.0, 165.0, 163.0, 163.0, 163.0, 163.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 108.0, 77.0, 76.0, 77.0, 77.0, 93.0, 77.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 109.0, 84.0, 85.0, 84.0, 97.0, 85.0, 90.0, 119.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 149.0, 152.0, 150.0, 149.0, 149.0, 151.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 67.0, 67.0, 59.0, 60.0, 61.0, 61.0, 60.0, 62.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 68.0, 69.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 86.0, 85.0, 93.0, 85.0, 85.0, 85.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 146.0, 136.0, 146.0, 133.0, 134.0, 132.0, 129.0, 215.0, 132.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 187.0, 181.0, 180.0, 183.0, 184.0, 181.0, 178.0, 179.0, 188.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 72.0, 69.0, 68.0, 69.0, 69.0, 69.0, 68.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1562.9, "alertThreshold": 2.0}]} 13:26:52 INFO - Return code: 0 13:26:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:26:53 INFO - # TBPL SUCCESS # 13:26:53 INFO - Running post-action listener: _resource_record_post_action 13:26:53 INFO - [mozharness: 2017-01-07 21:26:53.007081Z] Finished run-tests step (success) 13:26:53 INFO - Running post-run listener: _resource_record_post_run 13:26:53 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12288; Write bytes: 381857792; Read time: 132; Write time: 287580 13:26:53 INFO - TinderboxPrint: CPU usage
13.2% 13:26:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 132 13:26:53 INFO - TinderboxPrint: I/O write bytes / time
381,857,792 / 287,580 13:26:53 INFO - TinderboxPrint: CPU idle
8,321.4 (86.8%) 13:26:53 INFO - TinderboxPrint: CPU user
1,218.0 (12.7%) 13:26:53 INFO - TinderboxPrint: Swap in / out
0 / 0 13:26:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 100024320; Read time: 32; Write time: 104096 13:26:53 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 281833472; Read time: 80; Write time: 183484 13:26:53 INFO - Running post-run listener: _upload_blobber_files 13:26:53 INFO - Blob upload gear active. 13:26:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:26:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:26:54 INFO - (blobuploader) - INFO - Open directory for files ... 13:26:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:26:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:26:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:54 INFO - (blobuploader) - INFO - Done attempting. 13:26:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:26:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:26:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:57 INFO - (blobuploader) - INFO - Done attempting. 13:26:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:26:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:26:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:58 INFO - (blobuploader) - INFO - Done attempting. 13:26:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:26:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:59 INFO - (blobuploader) - INFO - Done attempting. 13:26:59 INFO - (blobuploader) - INFO - Iteration through files over. 13:26:59 INFO - Return code: 0 13:26:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:26:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:26:59 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cbfb8086fa09ae42cf85034c936dd023b20ade05f107398ff44edf400f1902e75f8c54f5aa2296f40881dbb086b536520304f978b18230aa59248ba455ef7d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6370ba118e168bdc20322f81738a20d260cf39601aaf91c6a2cfc56d13ca7cbd8b87d1e863832f3290935e1358092c7832d66dfa1519add7e2a193f9a41c33a1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e755dd68bced6037c47ffb42b2000325fe1aa0634710c7928ac246db8996849952bb7107a3521f9ea09e0c66b04ac4198dbbc7cbb590ea8e98853eed7757db5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83339297799651abe76ac47b9cae7c57cd427786fc03b0ad89c1002aeda737eb4e3c13e343c022d57b05046fbf7274f6e6bfdaa1b4a298691240aa2f0701c03"} 13:26:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:26:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:26:59 INFO - Contents: 13:26:59 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cbfb8086fa09ae42cf85034c936dd023b20ade05f107398ff44edf400f1902e75f8c54f5aa2296f40881dbb086b536520304f978b18230aa59248ba455ef7d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6370ba118e168bdc20322f81738a20d260cf39601aaf91c6a2cfc56d13ca7cbd8b87d1e863832f3290935e1358092c7832d66dfa1519add7e2a193f9a41c33a1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e755dd68bced6037c47ffb42b2000325fe1aa0634710c7928ac246db8996849952bb7107a3521f9ea09e0c66b04ac4198dbbc7cbb590ea8e98853eed7757db5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83339297799651abe76ac47b9cae7c57cd427786fc03b0ad89c1002aeda737eb4e3c13e343c022d57b05046fbf7274f6e6bfdaa1b4a298691240aa2f0701c03"} 13:26:59 INFO - Running post-run listener: copy_logs_to_upload_dir 13:26:59 INFO - Copying logs to upload dir... 13:26:59 INFO - mkdir: /builds/slave/test/build/upload/logs 13:26:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.080745 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-07 13:26:58.405394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 13:26:58.408870) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cbfb8086fa09ae42cf85034c936dd023b20ade05f107398ff44edf400f1902e75f8c54f5aa2296f40881dbb086b536520304f978b18230aa59248ba455ef7d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6370ba118e168bdc20322f81738a20d260cf39601aaf91c6a2cfc56d13ca7cbd8b87d1e863832f3290935e1358092c7832d66dfa1519add7e2a193f9a41c33a1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e755dd68bced6037c47ffb42b2000325fe1aa0634710c7928ac246db8996849952bb7107a3521f9ea09e0c66b04ac4198dbbc7cbb590ea8e98853eed7757db5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83339297799651abe76ac47b9cae7c57cd427786fc03b0ad89c1002aeda737eb4e3c13e343c022d57b05046fbf7274f6e6bfdaa1b4a298691240aa2f0701c03"} build_url:https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/GKnLaoKSQ2SAyCTQjylZHQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cbfb8086fa09ae42cf85034c936dd023b20ade05f107398ff44edf400f1902e75f8c54f5aa2296f40881dbb086b536520304f978b18230aa59248ba455ef7d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6370ba118e168bdc20322f81738a20d260cf39601aaf91c6a2cfc56d13ca7cbd8b87d1e863832f3290935e1358092c7832d66dfa1519add7e2a193f9a41c33a1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e755dd68bced6037c47ffb42b2000325fe1aa0634710c7928ac246db8996849952bb7107a3521f9ea09e0c66b04ac4198dbbc7cbb590ea8e98853eed7757db5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83339297799651abe76ac47b9cae7c57cd427786fc03b0ad89c1002aeda737eb4e3c13e343c022d57b05046fbf7274f6e6bfdaa1b4a298691240aa2f0701c03"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 13:26:58.433372) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:26:58.433683) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1483817317.583850-1540769460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:26:58.519084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 13:26:58.519384) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 13:26:58.519662) ========= ========= Total master_lag: 0.17 =========