builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1483668120.62 results: success (0) revision: a14094edbad78fc1d16e8d4c57902537cf286fd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.619314) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.619802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.620093) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.634770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.635049) ========= 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=836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 18:02:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.2M=0s 2017-01-05 18:02:01 (40.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125970 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.774654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.774987) ========= 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=836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017274 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:00.824172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 18:02:00.824523) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a14094edbad78fc1d16e8d4c57902537cf286fd1 --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 a14094edbad78fc1d16e8d4c57902537cf286fd1 --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=836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 18:02:01,775 truncating revision to first 12 chars 2017-01-05 18:02:01,775 Setting DEBUG logging. 2017-01-05 18:02:01,775 attempt 1/10 2017-01-05 18:02:01,775 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a14094edbad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 18:02:02,884 unpacking tar archive at: mozilla-central-a14094edbad7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.471750 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 18:02:02.309623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:02.309971) ========= script_repo_revision: a14094edbad78fc1d16e8d4c57902537cf286fd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:02.310408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:02.310715) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 18:02:02.321024) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-05 18:02:02.321375) ========= /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=836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:02:03 INFO - MultiFileLogger online at 20170105 18:02:03 in /builds/slave/test 18:02:03 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 18:02:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:02:03 INFO - {'append_to_log': False, 18:02:03 INFO - 'base_work_dir': '/builds/slave/test', 18:02:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:02:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:02:03 INFO - 'branch': 'Firefox-Non-PGO', 18:02:03 INFO - 'buildbot_json_path': 'buildprops.json', 18:02:03 INFO - 'config_files': ('talos/linux_config.py',), 18:02:03 INFO - 'default_actions': ('clobber', 18:02:03 INFO - 'read-buildbot-config', 18:02:03 INFO - 'download-and-extract', 18:02:03 INFO - 'populate-webroot', 18:02:03 INFO - 'create-virtualenv', 18:02:03 INFO - 'install', 18:02:03 INFO - 'run-tests'), 18:02:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:02:03 INFO - 'download_minidump_stackwalk': True, 18:02:03 INFO - 'download_symbols': 'ondemand', 18:02:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:02:03 INFO - 'tooltool.py': '/tools/tooltool.py', 18:02:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:02:03 INFO - '/tools/misc-python/virtualenv.py')}, 18:02:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:02:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:02:03 INFO - 'installer_path': 'installer.exe', 18:02:03 INFO - 'log_level': 'info', 18:02:03 INFO - 'log_name': 'talos', 18:02:03 INFO - 'log_to_console': True, 18:02:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:02:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:02:03 INFO - 'opt_config_files': (), 18:02:03 INFO - 'pip_index': False, 18:02:03 INFO - 'sps_profile': False, 18:02:03 INFO - 'sps_profile_interval': 0, 18:02:03 INFO - 'suite': 'dromaeojs', 18:02:03 INFO - 'system_bits': '32', 18:02:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:02:03 INFO - 'title': 'talos-linux64-ix-028', 18:02:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:02:03 INFO - 'use_talos_json': True, 18:02:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:02:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:02:03 INFO - 'work_dir': 'build'} 18:02:03 INFO - [mozharness: 2017-01-06 02:02:03.373678Z] Running clobber step. 18:02:03 INFO - Running pre-action listener: _resource_record_pre_action 18:02:03 INFO - Running main action method: clobber 18:02:03 INFO - rmtree: /builds/slave/test/build 18:02:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:02:03 INFO - Running post-action listener: _resource_record_post_action 18:02:03 INFO - [mozharness: 2017-01-06 02:02:03.819852Z] Finished clobber step (success) 18:02:03 INFO - [mozharness: 2017-01-06 02:02:03.819953Z] Running read-buildbot-config step. 18:02:03 INFO - Running pre-action listener: _resource_record_pre_action 18:02:03 INFO - Running main action method: read_buildbot_config 18:02:03 INFO - Using buildbot properties: 18:02:03 INFO - { 18:02:03 INFO - "project": "", 18:02:03 INFO - "product": "firefox", 18:02:03 INFO - "who": "kwierso@gmail.com", 18:02:03 INFO - "installer_path": "public/build/target.tar.bz2", 18:02:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:02:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:02:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:02:03 INFO - "stage_platform": "linux64", 18:02:03 INFO - "basedir": "/builds/slave/test", 18:02:03 INFO - "buildnumber": 902, 18:02:03 INFO - "platform": "ubuntu64_hw", 18:02:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:02:03 INFO - "slavebuilddir": "test", 18:02:03 INFO - "taskId": "EsmzmxhuTAimyeJcVgWw1g", 18:02:03 INFO - "branch": "mozilla-central", 18:02:03 INFO - "script_repo_revision": "production", 18:02:03 INFO - "revision": "a14094edbad78fc1d16e8d4c57902537cf286fd1", 18:02:03 INFO - "slavename": "talos-linux64-ix-028", 18:02:03 INFO - "repo_path": "mozilla-central" 18:02:03 INFO - } 18:02:03 INFO - Finding installer, test and symbols from parent task. 18:02:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EsmzmxhuTAimyeJcVgWw1g'}, attempt #1 18:02:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EsmzmxhuTAimyeJcVgWw1g'}, attempt #1 18:02:04 INFO - Task dependencies: TCw6MSSUSBWiwJ9qVfHxpw 18:02:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw'}, attempt #1 18:02:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2 18:02:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/test_packages.json 18:02:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.crashreporter-symbols.zip 18:02:05 INFO - Running post-action listener: _resource_record_post_action 18:02:05 INFO - [mozharness: 2017-01-06 02:02:05.278429Z] Finished read-buildbot-config step (success) 18:02:05 INFO - [mozharness: 2017-01-06 02:02:05.278646Z] Running download-and-extract step. 18:02:05 INFO - Running pre-action listener: _resource_record_pre_action 18:02:05 INFO - Running main action method: download_and_extract 18:02:05 INFO - mkdir: /builds/slave/test/build/tests 18:02:05 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')]} 18:02:05 INFO - trying https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.test_packages.json 18:02:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:02:05 INFO - Downloaded 896 bytes. 18:02:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:02:05 INFO - Using the following test package requirements: 18:02:05 INFO - {u'common': [u'target.common.tests.zip'], 18:02:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:02:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:02:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:02:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:02:05 INFO - u'mozbase': [u'target.common.tests.zip'], 18:02:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:02:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:02:05 INFO - u'web-platform': [u'target.common.tests.zip', 18:02:05 INFO - u'target.web-platform.tests.zip'], 18:02:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:02:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:02:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.common.tests.zip 18:02:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:02:05 INFO - Fetch https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.common.tests.zip into memory 18:02:06 INFO - Expected file size: 20246597 18:02:06 INFO - Obtained file size: 20246597 18:02:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:02:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.common.tests.zip 18:02:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:02:10 INFO - Fetch https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.common.tests.zip into memory 18:02:11 INFO - Expected file size: 20246597 18:02:11 INFO - Obtained file size: 20246597 18:02:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.talos.tests.zip 18:02:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:02:15 INFO - Fetch https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.talos.tests.zip into memory 18:02:16 INFO - Expected file size: 14403730 18:02:16 INFO - Obtained file size: 14403730 18:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:16 INFO - trying https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2 18:02:16 INFO - Downloading https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:02:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:02:17 INFO - Downloaded 55875955 bytes. 18:02:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2 18:02:17 INFO - mkdir: /builds/slave/test/properties 18:02:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:02:17 INFO - Writing to file /builds/slave/test/properties/build_url 18:02:17 INFO - Contents: 18:02:17 INFO - build_url:https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2 18:02:17 INFO - Running post-action listener: _resource_record_post_action 18:02:17 INFO - Running post-action listener: set_extra_try_arguments 18:02:17 INFO - [mozharness: 2017-01-06 02:02:17.815521Z] Finished download-and-extract step (success) 18:02:17 INFO - [mozharness: 2017-01-06 02:02:17.815617Z] Running populate-webroot step. 18:02:17 INFO - Running pre-action listener: _resource_record_pre_action 18:02:17 INFO - Running main action method: populate_webroot 18:02:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:02:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:02:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:02:17 INFO - '--tppagecycles', 18:02:17 INFO - '7'], 18:02:17 INFO - 'tests': ['tsvgm']}}, 18:02:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:02:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:02:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:02:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:02:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['damp', 'tps']}, 18:02:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'tests': ['damp', 'tps']}, 18:02:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['dromaeo_dom']}, 18:02:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:02:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['basic_compositor_video']}, 18:02:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:02:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['a11yr', 18:02:17 INFO - 'ts_paint', 18:02:17 INFO - 'tpaint', 18:02:17 INFO - 'sessionrestore', 18:02:17 INFO - 'sessionrestore_no_auto_restore', 18:02:17 INFO - 'tabpaint']}, 18:02:17 INFO - 'other-e10s': {'tests': ['a11yr', 18:02:17 INFO - 'ts_paint', 18:02:17 INFO - 'tpaint', 18:02:17 INFO - 'sessionrestore', 18:02:17 INFO - 'sessionrestore_no_auto_restore', 18:02:17 INFO - 'tabpaint']}, 18:02:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:02:17 INFO - 'ts_paint', 18:02:17 INFO - 'tpaint', 18:02:17 INFO - 'sessionrestore', 18:02:17 INFO - 'sessionrestore_no_auto_restore', 18:02:17 INFO - 'tabpaint']}, 18:02:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:02:17 INFO - 'ts_paint', 18:02:17 INFO - 'tpaint', 18:02:17 INFO - 'sessionrestore', 18:02:17 INFO - 'sessionrestore_no_auto_restore', 18:02:17 INFO - 'tabpaint']}, 18:02:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['a11yr', 18:02:17 INFO - 'ts_paint', 18:02:17 INFO - 'tpaint', 18:02:17 INFO - 'sessionrestore', 18:02:17 INFO - 'sessionrestore_no_auto_restore', 18:02:17 INFO - 'tabpaint']}, 18:02:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['a11yr', 18:02:17 INFO - 'ts_paint', 18:02:17 INFO - 'tpaint', 18:02:17 INFO - 'sessionrestore', 18:02:17 INFO - 'sessionrestore_no_auto_restore', 18:02:17 INFO - 'tabpaint']}, 18:02:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['tsvgx', 18:02:17 INFO - 'tsvgr_opacity', 18:02:17 INFO - 'tart', 18:02:17 INFO - 'tscrollx', 18:02:17 INFO - 'cart', 18:02:17 INFO - 'tsvg_static']}, 18:02:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:02:17 INFO - 'tsvgr_opacity', 18:02:17 INFO - 'tart', 18:02:17 INFO - 'tscrollx', 18:02:17 INFO - 'cart', 18:02:17 INFO - 'tsvg_static']}, 18:02:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'talos_options': ['--disable-e10s'], 18:02:17 INFO - 'tests': ['tp5o']}, 18:02:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:02:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'talos_options': ['--disable-e10s', 18:02:17 INFO - '--xperf_path', 18:02:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:17 INFO - 'tests': ['tp5n']}, 18:02:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:02:17 INFO - 'talos_options': ['--xperf_path', 18:02:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:17 INFO - 'tests': ['tp5n']}}, 18:02:17 INFO - 'talos.zip': {'path': '', 18:02:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:02:17 INFO - Running post-action listener: _resource_record_post_action 18:02:17 INFO - [mozharness: 2017-01-06 02:02:17.828759Z] Finished populate-webroot step (success) 18:02:17 INFO - [mozharness: 2017-01-06 02:02:17.828853Z] Running create-virtualenv step. 18:02:17 INFO - Running pre-action listener: _resource_record_pre_action 18:02:17 INFO - Running main action method: create_virtualenv 18:02:17 INFO - Creating virtualenv /builds/slave/test/build/venv 18:02:17 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 18:02:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:02:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:02:17 INFO - Using real prefix '/usr' 18:02:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:02:18 INFO - Installing distribute.............................................................................................................................................................................................done. 18:02:20 INFO - Installing pip.................done. 18:02:20 INFO - Return code: 0 18:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:02:20 INFO - Reading from file tmpfile_stdout 18:02:20 INFO - Output received: 18:02:20 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) 18:02:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:02:20 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')]} 18:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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')]} 18:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:20 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 18:02:20 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 18:02:20 INFO - Using env: {'DISPLAY': ':0', 18:02:20 INFO - 'HOME': '/home/cltbld', 18:02:20 INFO - 'LANG': 'en_US.UTF-8', 18:02:20 INFO - 'LANGUAGE': 'en_US:en', 18:02:20 INFO - 'LOGNAME': 'cltbld', 18:02:20 INFO - 'MAIL': '/var/mail/cltbld', 18:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:20 INFO - 'MOZ_NO_REMOTE': '1', 18:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:20 INFO - 'NO_EM_RESTART': '1', 18:02:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:20 INFO - 'PWD': '/builds/slave/test', 18:02:20 INFO - 'SHELL': '/bin/bash', 18:02:20 INFO - 'SHLVL': '1', 18:02:20 INFO - 'TERM': 'linux', 18:02:20 INFO - 'TMOUT': '86400', 18:02:20 INFO - 'USER': 'cltbld', 18:02:20 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:20 INFO - '_': '/tools/buildbot/bin/python'} 18:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:20 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 18:02:20 INFO - Cleaning up... 18:02:20 INFO - Return code: 0 18:02:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:02:20 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')]} 18:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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')]} 18:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:20 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 18:02:20 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 18:02:20 INFO - Using env: {'DISPLAY': ':0', 18:02:20 INFO - 'HOME': '/home/cltbld', 18:02:20 INFO - 'LANG': 'en_US.UTF-8', 18:02:20 INFO - 'LANGUAGE': 'en_US:en', 18:02:20 INFO - 'LOGNAME': 'cltbld', 18:02:20 INFO - 'MAIL': '/var/mail/cltbld', 18:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:20 INFO - 'MOZ_NO_REMOTE': '1', 18:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:20 INFO - 'NO_EM_RESTART': '1', 18:02:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:20 INFO - 'PWD': '/builds/slave/test', 18:02:20 INFO - 'SHELL': '/bin/bash', 18:02:20 INFO - 'SHLVL': '1', 18:02:20 INFO - 'TERM': 'linux', 18:02:20 INFO - 'TMOUT': '86400', 18:02:20 INFO - 'USER': 'cltbld', 18:02:20 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:20 INFO - '_': '/tools/buildbot/bin/python'} 18:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:21 INFO - Downloading/unpacking psutil>=3.1.1 18:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:02:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:23 INFO - Installing collected packages: psutil 18:02:23 INFO - Running setup.py install for psutil 18:02:23 INFO - building 'psutil._psutil_linux' extension 18:02:23 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 18:02:23 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 18:02:23 INFO - building 'psutil._psutil_posix' extension 18:02:23 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 18:02:23 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 18:02:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:23 INFO - Successfully installed psutil 18:02:23 INFO - Cleaning up... 18:02:23 INFO - Return code: 0 18:02:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:02:23 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')]} 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:23 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')]} 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:23 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:23 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 18:02:23 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 18:02:23 INFO - Using env: {'DISPLAY': ':0', 18:02:23 INFO - 'HOME': '/home/cltbld', 18:02:23 INFO - 'LANG': 'en_US.UTF-8', 18:02:23 INFO - 'LANGUAGE': 'en_US:en', 18:02:23 INFO - 'LOGNAME': 'cltbld', 18:02:23 INFO - 'MAIL': '/var/mail/cltbld', 18:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:23 INFO - 'MOZ_NO_REMOTE': '1', 18:02:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:23 INFO - 'NO_EM_RESTART': '1', 18:02:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:23 INFO - 'PWD': '/builds/slave/test', 18:02:23 INFO - 'SHELL': '/bin/bash', 18:02:23 INFO - 'SHLVL': '1', 18:02:23 INFO - 'TERM': 'linux', 18:02:23 INFO - 'TMOUT': '86400', 18:02:23 INFO - 'USER': 'cltbld', 18:02:23 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:23 INFO - '_': '/tools/buildbot/bin/python'} 18:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:02:26 INFO - Installing collected packages: mozsystemmonitor 18:02:26 INFO - Running setup.py install for mozsystemmonitor 18:02:26 INFO - Successfully installed mozsystemmonitor 18:02:26 INFO - Cleaning up... 18:02:26 INFO - Return code: 0 18:02:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:26 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:26 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 18:02:26 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 18:02:26 INFO - Using env: {'DISPLAY': ':0', 18:02:26 INFO - 'HOME': '/home/cltbld', 18:02:26 INFO - 'LANG': 'en_US.UTF-8', 18:02:26 INFO - 'LANGUAGE': 'en_US:en', 18:02:26 INFO - 'LOGNAME': 'cltbld', 18:02:26 INFO - 'MAIL': '/var/mail/cltbld', 18:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:26 INFO - 'MOZ_NO_REMOTE': '1', 18:02:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:26 INFO - 'NO_EM_RESTART': '1', 18:02:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:26 INFO - 'PWD': '/builds/slave/test', 18:02:26 INFO - 'SHELL': '/bin/bash', 18:02:26 INFO - 'SHLVL': '1', 18:02:26 INFO - 'TERM': 'linux', 18:02:26 INFO - 'TMOUT': '86400', 18:02:26 INFO - 'USER': 'cltbld', 18:02:26 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:26 INFO - '_': '/tools/buildbot/bin/python'} 18:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:26 INFO - Downloading/unpacking jsonschema==2.5.1 18:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:02:28 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 18:02:28 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 18:02:28 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 18:02:28 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 18:02:28 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 18:02:28 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 18:02:28 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:28 INFO - warning: no files found matching '*.txt' 18:02:28 INFO - no previously-included directories found matching 'build' 18:02:28 INFO - no previously-included directories found matching 'dist' 18:02:28 INFO - no previously-included directories found matching '.git*' 18:02:28 INFO - Installing collected packages: jsonschema, functools32 18:02:28 INFO - Running setup.py install for functools32 18:02:28 INFO - warning: no files found matching '*.txt' 18:02:28 INFO - no previously-included directories found matching 'build' 18:02:28 INFO - no previously-included directories found matching 'dist' 18:02:28 INFO - no previously-included directories found matching '.git*' 18:02:28 INFO - Successfully installed jsonschema functools32 18:02:28 INFO - Cleaning up... 18:02:28 INFO - Return code: 0 18:02:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:28 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:28 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 18:02:28 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 18:02:28 INFO - Using env: {'DISPLAY': ':0', 18:02:28 INFO - 'HOME': '/home/cltbld', 18:02:28 INFO - 'LANG': 'en_US.UTF-8', 18:02:28 INFO - 'LANGUAGE': 'en_US:en', 18:02:28 INFO - 'LOGNAME': 'cltbld', 18:02:28 INFO - 'MAIL': '/var/mail/cltbld', 18:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:28 INFO - 'MOZ_NO_REMOTE': '1', 18:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:28 INFO - 'NO_EM_RESTART': '1', 18:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:28 INFO - 'PWD': '/builds/slave/test', 18:02:28 INFO - 'SHELL': '/bin/bash', 18:02:28 INFO - 'SHLVL': '1', 18:02:28 INFO - 'TERM': 'linux', 18:02:28 INFO - 'TMOUT': '86400', 18:02:28 INFO - 'USER': 'cltbld', 18:02:28 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:28 INFO - '_': '/tools/buildbot/bin/python'} 18:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:02:29 INFO - Cleaning up... 18:02:29 INFO - Return code: 0 18:02:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:02:29 INFO - Using env: {'DISPLAY': ':0', 18:02:29 INFO - 'HOME': '/home/cltbld', 18:02:29 INFO - 'LANG': 'en_US.UTF-8', 18:02:29 INFO - 'LANGUAGE': 'en_US:en', 18:02:29 INFO - 'LOGNAME': 'cltbld', 18:02:29 INFO - 'MAIL': '/var/mail/cltbld', 18:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:29 INFO - 'MOZ_NO_REMOTE': '1', 18:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:29 INFO - 'NO_EM_RESTART': '1', 18:02:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:29 INFO - 'PWD': '/builds/slave/test', 18:02:29 INFO - 'SHELL': '/bin/bash', 18:02:29 INFO - 'SHLVL': '1', 18:02:29 INFO - 'TERM': 'linux', 18:02:29 INFO - 'TMOUT': '86400', 18:02:29 INFO - 'USER': 'cltbld', 18:02:29 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:29 INFO - '_': '/tools/buildbot/bin/python'} 18:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:29 INFO - Downloading/unpacking blobuploader==1.2.4 18:02:29 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 18:02:29 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 18:02:29 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 18:02:29 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 18:02:29 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 18:02:29 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 18:02:31 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:32 INFO - Downloading docopt-0.6.1.tar.gz 18:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:32 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:32 INFO - Running setup.py install for blobuploader 18:02:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:32 INFO - Running setup.py install for requests 18:02:32 INFO - Running setup.py install for docopt 18:02:32 INFO - Successfully installed blobuploader requests docopt 18:02:32 INFO - Cleaning up... 18:02:32 INFO - Return code: 0 18:02:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:32 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')]} 18:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:32 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')]} 18:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:32 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:32 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 18:02:32 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 18:02:32 INFO - Using env: {'DISPLAY': ':0', 18:02:32 INFO - 'HOME': '/home/cltbld', 18:02:32 INFO - 'LANG': 'en_US.UTF-8', 18:02:32 INFO - 'LANGUAGE': 'en_US:en', 18:02:32 INFO - 'LOGNAME': 'cltbld', 18:02:32 INFO - 'MAIL': '/var/mail/cltbld', 18:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:32 INFO - 'MOZ_NO_REMOTE': '1', 18:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:32 INFO - 'NO_EM_RESTART': '1', 18:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:32 INFO - 'PWD': '/builds/slave/test', 18:02:32 INFO - 'SHELL': '/bin/bash', 18:02:32 INFO - 'SHLVL': '1', 18:02:32 INFO - 'TERM': 'linux', 18:02:32 INFO - 'TMOUT': '86400', 18:02:32 INFO - 'USER': 'cltbld', 18:02:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:32 INFO - '_': '/tools/buildbot/bin/python'} 18:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:32 INFO - Running setup.py (path:/tmp/pip-pjsw6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:33 INFO - Running setup.py (path:/tmp/pip-uW40Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:33 INFO - Running setup.py (path:/tmp/pip-FqtCDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:33 INFO - Running setup.py (path:/tmp/pip-01ona6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:33 INFO - Running setup.py (path:/tmp/pip-HcDde_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:33 INFO - Running setup.py (path:/tmp/pip-XzKIoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:33 INFO - Running setup.py (path:/tmp/pip-4tkG5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:33 INFO - Running setup.py (path:/tmp/pip-QmVlMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:33 INFO - Running setup.py (path:/tmp/pip-HkhTsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:33 INFO - Running setup.py (path:/tmp/pip-cXoHtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:33 INFO - Running setup.py (path:/tmp/pip-kGDEDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:33 INFO - Running setup.py (path:/tmp/pip-Nv4ME8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:33 INFO - Running setup.py (path:/tmp/pip-CmD9ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:33 INFO - Running setup.py (path:/tmp/pip-y00D78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:34 INFO - Running setup.py (path:/tmp/pip-ht6hoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:34 INFO - Running setup.py (path:/tmp/pip-za6iQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:34 INFO - Running setup.py (path:/tmp/pip-5w5lVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:34 INFO - Running setup.py install for manifestparser 18:02:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:34 INFO - Running setup.py install for mozcrash 18:02:34 INFO - Running setup.py install for mozdebug 18:02:34 INFO - Running setup.py install for mozdevice 18:02:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:02:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:34 INFO - Running setup.py install for mozfile 18:02:34 INFO - Running setup.py install for mozhttpd 18:02:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:34 INFO - Running setup.py install for mozinfo 18:02:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:34 INFO - Running setup.py install for mozInstall 18:02:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Running setup.py install for mozleak 18:02:35 INFO - Running setup.py install for mozlog 18:02:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Running setup.py install for moznetwork 18:02:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Running setup.py install for mozprocess 18:02:35 INFO - Running setup.py install for mozprofile 18:02:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Running setup.py install for mozrunner 18:02:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:35 INFO - Running setup.py install for mozscreenshot 18:02:35 INFO - Running setup.py install for moztest 18:02:35 INFO - Running setup.py install for mozversion 18:02:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:36 INFO - Cleaning up... 18:02:36 INFO - Return code: 0 18:02:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:36 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:36 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 18:02:36 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 18:02:36 INFO - Using env: {'DISPLAY': ':0', 18:02:36 INFO - 'HOME': '/home/cltbld', 18:02:36 INFO - 'LANG': 'en_US.UTF-8', 18:02:36 INFO - 'LANGUAGE': 'en_US:en', 18:02:36 INFO - 'LOGNAME': 'cltbld', 18:02:36 INFO - 'MAIL': '/var/mail/cltbld', 18:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:36 INFO - 'MOZ_NO_REMOTE': '1', 18:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:36 INFO - 'NO_EM_RESTART': '1', 18:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:36 INFO - 'PWD': '/builds/slave/test', 18:02:36 INFO - 'SHELL': '/bin/bash', 18:02:36 INFO - 'SHLVL': '1', 18:02:36 INFO - 'TERM': 'linux', 18:02:36 INFO - 'TMOUT': '86400', 18:02:36 INFO - 'USER': 'cltbld', 18:02:36 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:36 INFO - '_': '/tools/buildbot/bin/python'} 18:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:36 INFO - Running setup.py (path:/tmp/pip-_nNEwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:36 INFO - Running setup.py (path:/tmp/pip-B9Sx0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:36 INFO - Running setup.py (path:/tmp/pip-ZNFOw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:36 INFO - Running setup.py (path:/tmp/pip-nYA3j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:36 INFO - Running setup.py (path:/tmp/pip-okyeAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:36 INFO - Running setup.py (path:/tmp/pip-QkQcUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:36 INFO - Running setup.py (path:/tmp/pip-6J5bhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:36 INFO - Running setup.py (path:/tmp/pip-6TtsQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:36 INFO - Running setup.py (path:/tmp/pip-9lcu6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:36 INFO - Running setup.py (path:/tmp/pip-zwi4qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:37 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:37 INFO - Running setup.py (path:/tmp/pip-RHkA7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:37 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:37 INFO - Running setup.py (path:/tmp/pip-OjSrG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:37 INFO - Running setup.py (path:/tmp/pip-Wgod_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:37 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:37 INFO - Running setup.py (path:/tmp/pip-6XsT5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:37 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:37 INFO - Running setup.py (path:/tmp/pip-B6E20J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:37 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:37 INFO - Running setup.py (path:/tmp/pip-qKpR4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:37 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)) 18:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:37 INFO - Running setup.py (path:/tmp/pip-QhT1qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:37 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)) 18:02:37 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)) 18:02:37 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)) 18:02:37 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)) 18:02:37 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)) 18:02:37 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)) 18:02:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:39 INFO - Downloading blessings-1.6.tar.gz 18:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:39 INFO - Installing collected packages: blessings 18:02:39 INFO - Running setup.py install for blessings 18:02:39 INFO - Successfully installed blessings 18:02:39 INFO - Cleaning up... 18:02:39 INFO - Return code: 0 18:02:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:40 INFO - Reading from file tmpfile_stdout 18:02:40 INFO - Current package versions: 18:02:40 INFO - argparse == 1.2.1 18:02:40 INFO - blessings == 1.6 18:02:40 INFO - blobuploader == 1.2.4 18:02:40 INFO - docopt == 0.6.1 18:02:40 INFO - functools32 == 3.2.3-2 18:02:40 INFO - jsonschema == 2.5.1 18:02:40 INFO - manifestparser == 1.1 18:02:40 INFO - mozInstall == 1.12 18:02:40 INFO - mozcrash == 1.0 18:02:40 INFO - mozdebug == 0.1 18:02:40 INFO - mozdevice == 0.49 18:02:40 INFO - mozfile == 1.2 18:02:40 INFO - mozhttpd == 0.7 18:02:40 INFO - mozinfo == 0.9 18:02:40 INFO - mozleak == 0.1 18:02:40 INFO - mozlog == 3.4 18:02:40 INFO - moznetwork == 0.27 18:02:40 INFO - mozprocess == 0.23 18:02:40 INFO - mozprofile == 0.28 18:02:40 INFO - mozrunner == 6.13 18:02:40 INFO - mozscreenshot == 0.1 18:02:40 INFO - mozsystemmonitor == 0.3 18:02:40 INFO - moztest == 0.8 18:02:40 INFO - mozversion == 1.4 18:02:40 INFO - psutil == 3.1.1 18:02:40 INFO - requests == 1.2.3 18:02:40 INFO - wsgiref == 0.1.2 18:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:40 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 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02:40 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 18:02:40 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 18:02:40 INFO - Using env: {'DISPLAY': ':0', 18:02:40 INFO - 'HOME': '/home/cltbld', 18:02:40 INFO - 'LANG': 'en_US.UTF-8', 18:02:40 INFO - 'LANGUAGE': 'en_US:en', 18:02:40 INFO - 'LOGNAME': 'cltbld', 18:02:40 INFO - 'MAIL': '/var/mail/cltbld', 18:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:40 INFO - 'MOZ_NO_REMOTE': '1', 18:02:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:40 INFO - 'NO_EM_RESTART': '1', 18:02:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:40 INFO - 'PWD': '/builds/slave/test', 18:02:40 INFO - 'SHELL': '/bin/bash', 18:02:40 INFO - 'SHLVL': '1', 18:02:40 INFO - 'TERM': 'linux', 18:02:40 INFO - 'TMOUT': '86400', 18:02:40 INFO - 'USER': 'cltbld', 18:02:40 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:40 INFO - '_': '/tools/buildbot/bin/python'} 18:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 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)) 18:02:40 INFO - Cleaning up... 18:02:40 INFO - Return code: 0 18:02:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a20630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, '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': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', '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 18:02: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', 'jsonschema'] in /builds/slave/test/build 18:02: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 jsonschema 18:02:40 INFO - Using env: {'DISPLAY': ':0', 18:02:40 INFO - 'HOME': '/home/cltbld', 18:02:40 INFO - 'LANG': 'en_US.UTF-8', 18:02:40 INFO - 'LANGUAGE': 'en_US:en', 18:02:40 INFO - 'LOGNAME': 'cltbld', 18:02:40 INFO - 'MAIL': '/var/mail/cltbld', 18:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:40 INFO - 'MOZ_NO_REMOTE': '1', 18:02:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:40 INFO - 'NO_EM_RESTART': '1', 18:02:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:40 INFO - 'PWD': '/builds/slave/test', 18:02:40 INFO - 'SHELL': '/bin/bash', 18:02:40 INFO - 'SHLVL': '1', 18:02:40 INFO - 'TERM': 'linux', 18:02:40 INFO - 'TMOUT': '86400', 18:02:40 INFO - 'USER': 'cltbld', 18:02:40 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:40 INFO - '_': '/tools/buildbot/bin/python'} 18:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:02:40 INFO - Cleaning up... 18:02:40 INFO - Return code: 0 18:02:40 INFO - Running post-action listener: _resource_record_post_action 18:02:40 INFO - Running post-action listener: _start_resource_monitoring 18:02:40 INFO - Starting resource monitoring. 18:02:40 INFO - [mozharness: 2017-01-06 02:02:40.522884Z] Finished create-virtualenv step (success) 18:02:40 INFO - [mozharness: 2017-01-06 02:02:40.523386Z] Running install step. 18:02:40 INFO - Running pre-action listener: _resource_record_pre_action 18:02:40 INFO - Running main action method: install 18:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:40 INFO - Reading from file tmpfile_stdout 18:02:40 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:40 INFO - Reading from file tmpfile_stdout 18:02:40 INFO - Output received: 18:02:40 INFO - Usage: mozinstall [options] installer 18:02:40 INFO - Options: 18:02:40 INFO - -h, --help show this help message and exit 18:02:40 INFO - -d DEST, --destination=DEST 18:02:40 INFO - Directory to install application into. [default: 18:02:40 INFO - "/builds/slave/test"] 18:02:40 INFO - --app=APP Application being installed. [default: firefox] 18:02:40 INFO - mkdir: /builds/slave/test/build/application 18:02:40 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'] 18:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:02:56 INFO - Reading from file tmpfile_stdout 18:02:56 INFO - Output received: 18:02:56 INFO - /builds/slave/test/build/application/firefox/firefox 18:02:56 INFO - Running post-action listener: _resource_record_post_action 18:02:56 INFO - [mozharness: 2017-01-06 02:02:56.899588Z] Finished install step (success) 18:02:56 INFO - [mozharness: 2017-01-06 02:02:56.899835Z] Running run-tests step. 18:02:56 INFO - Running pre-action listener: _resource_record_pre_action 18:02:56 INFO - Running main action method: run_tests 18:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:56 INFO - Python 2.7.3 18:02:56 INFO - Return code: 0 18:02:56 INFO - grabbing minidump binary from tooltool 18:02:56 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')]} 18:02:56 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 0x2aa9850>, '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 0x2a1e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:56 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 18:02:56 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 18:02:56 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 18:02:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:56 INFO - Return code: 0 18:02:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:02:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:02:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:56 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/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:02:56 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/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 18:02:56 INFO - Using env: {'DISPLAY': ':0', 18:02:56 INFO - 'HOME': '/home/cltbld', 18:02:56 INFO - 'LANG': 'en_US.UTF-8', 18:02:56 INFO - 'LANGUAGE': 'en_US:en', 18:02:56 INFO - 'LOGNAME': 'cltbld', 18:02:56 INFO - 'MAIL': '/var/mail/cltbld', 18:02:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:56 INFO - 'MOZ_NO_REMOTE': '1', 18:02:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:56 INFO - 'NO_EM_RESTART': '1', 18:02:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:56 INFO - 'PWD': '/builds/slave/test', 18:02:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:56 INFO - 'SHELL': '/bin/bash', 18:02:56 INFO - 'SHLVL': '1', 18:02:56 INFO - 'TERM': 'linux', 18:02:56 INFO - 'TMOUT': '86400', 18:02:56 INFO - 'USER': 'cltbld', 18:02:56 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777', 18:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:56 INFO - '_': '/tools/buildbot/bin/python'} 18:02:56 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/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:02:57 INFO - mozversion application_buildid: 20170106013045 18:02:57 INFO - mozversion application_changeset: a14094edbad78fc1d16e8d4c57902537cf286fd1 18:02:57 INFO - mozversion application_display_name: Nightly 18:02:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:57 INFO - mozversion application_name: Firefox 18:02:57 INFO - mozversion application_remotingname: firefox 18:02:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:57 INFO - mozversion application_vendor: Mozilla 18:02:57 INFO - mozversion application_version: 53.0a1 18:02:57 INFO - mozversion platform_buildid: 20170106013045 18:02:57 INFO - mozversion platform_changeset: a14094edbad78fc1d16e8d4c57902537cf286fd1 18:02:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:57 INFO - mozversion platform_version: 53.0a1 18:02:57 INFO - using testdate: 1483668177 18:02:57 INFO - actual date: 1483668177 18:02:57 INFO - starting webserver on 'localhost:39969' 18:02:57 INFO - SUITE-START | Running 2 tests 18:02:57 INFO - TEST-START | dromaeo_css 18:02:57 INFO - operating with platform_type : linux_ 18:02:57 INFO - Initialising browser for dromaeo_css test... 18:02:57 INFO - TEST-INFO | started process 4737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf5NGex/profile http://localhost:39969/getInfo.html) 18:02:57 INFO - PROCESS | 4737 | 1483668177694 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 18:03:01 INFO - PROCESS | 4737 | __metrics Screen width/height:1600/1200 18:03:01 INFO - PROCESS | 4737 | colorDepth:24 18:03:01 INFO - PROCESS | 4737 | Browser inner width/height: 1024/697 18:03:01 INFO - PROCESS | 4737 | __metrics 18:03:01 INFO - PROCESS | 4737 | 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" 18:03:01 INFO - PROCESS | 4737 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:03:02 INFO - PROCESS | 4737 | console.error: PushService: 18:03:02 INFO - PROCESS | 4737 | stateChangeProcessEnqueue: Error transitioning state 18:03:02 INFO - PROCESS | 4737 | UnknownError 18:03:03 INFO - TEST-INFO | 4737: exit 0 18:03:03 INFO - Browser initialized. 18:03:03 INFO - Running cycle 1/1 for dromaeo_css test... 18:03:03 INFO - TEST-INFO | started process 4821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf5NGex/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:15 INFO - PROCESS | 4821 | RSS: Main: 151183360 18:03:15 INFO - PROCESS | 4821 | 18:06:20 INFO - PROCESS | 4821 | Cycle 1(1): loaded http://localhost:39969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39969/tests/dromaeo/cssquery-ext.html) 18:06:21 INFO - PROCESS | 4821 | RSS: Main: 240422912 18:06:21 INFO - PROCESS | 4821 | 18:08:04 INFO - PROCESS | 4821 | ************************* 18:08:04 INFO - PROCESS | 4821 | A coding exception was thrown and uncaught in a Task. 18:08:04 INFO - PROCESS | 4821 | 18:08:04 INFO - PROCESS | 4821 | Full message: ReferenceError: fetch is not defined 18:08:04 INFO - PROCESS | 4821 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 18:08:04 INFO - PROCESS | 4821 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 18:08:04 INFO - PROCESS | 4821 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 18:08:04 INFO - PROCESS | 4821 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:08:04 INFO - PROCESS | 4821 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:08:04 INFO - PROCESS | 4821 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:08:04 INFO - PROCESS | 4821 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 18:08:04 INFO - PROCESS | 4821 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:08:04 INFO - PROCESS | 4821 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:08:04 INFO - PROCESS | 4821 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:08:04 INFO - PROCESS | 4821 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 18:08:04 INFO - PROCESS | 4821 | 18:08:04 INFO - PROCESS | 4821 | ************************* 18:09:24 INFO - PROCESS | 4821 | Cycle 1(1): loaded http://localhost:39969/tests/dromaeo/cssquery-ext.html (next: http://localhost:39969/tests/dromaeo/cssquery-jquery.html) 18:09:25 INFO - PROCESS | 4821 | RSS: Main: 247177216 18:09:25 INFO - PROCESS | 4821 | 18:12:27 INFO - PROCESS | 4821 | Cycle 1(1): loaded http://localhost:39969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39969/tests/dromaeo/cssquery-mootools.html) 18:12:27 INFO - PROCESS | 4821 | RSS: Main: 213999616 18:12:27 INFO - PROCESS | 4821 | 18:15:29 INFO - PROCESS | 4821 | Cycle 1(1): loaded http://localhost:39969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39969/tests/dromaeo/cssquery-prototype.html) 18:15:30 INFO - PROCESS | 4821 | RSS: Main: 203685888 18:15:30 INFO - PROCESS | 4821 | 18:18:33 INFO - PROCESS | 4821 | Cycle 1(1): loaded http://localhost:39969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39969/tests/dromaeo/cssquery-yui.html) 18:18:34 INFO - PROCESS | 4821 | RSS: Main: 186998784 18:18:34 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | Cycle 1(1): loaded http://localhost:39969/tests/dromaeo/cssquery-yui.html (next: http://localhost:39969/tests/dromaeo/cssquery-dojo.html) 18:21:37 INFO - PROCESS | 4821 | RSS: Main: 274186240 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | __start_tp_report 18:21:37 INFO - PROCESS | 4821 | _x_x_mozilla_page_load 18:21:37 INFO - PROCESS | 4821 | _x_x_mozilla_page_load_details 18:21:37 INFO - PROCESS | 4821 | |i|pagename|runs| 18:21:37 INFO - PROCESS | 4821 | |0;dojo.html;2584.302238395633;2598.3634009667635;2603.921882343529;2624.2914413108456;2625.26492582077;4610.193216187162;4727.574518293353;4759.571638552529;4763.285507173926;4768.046390721861;4643.860684179472;4703.811847526094;4724.669273150881;4752.334673145754;4802.927956080662;4316.784160791964;4317.287647538153;4340.240457919851;4367.301231802912;4420.248557745191;4007.192396609312;4329.134173165364;4379.4525684289465;4412.205802955466;4445.644348452132;4276.080642661812;4308.439902812645;4322.567743225683;4336.479622445327;4431.1802316571375;4341.240282950471;4351.238533256667;4356.281213599752;4358.4769827620885;4359.760213188273;4326.437563116775;4329.7618630975285;4332.350147477872;4337.913241735179;4341.869743907688;4634.976825115887;4659.464161621389;4700.29495575663;4721.244600863845;4781.211100168468;5016.147254966664;5026.045051440251;5029.7736601853;5035.96762663653;5082.923756143661;5046.5205805448795;5055.216441451544;5071.518205770446;5077.365329333833;5111.2333150027425;4552.135094332067;4571.6799824012;4590.862274131781;4730.160628163714;4784.33019377284;5013.724245166478;5096.821611243588;5135.101357262464;5173.948260517347;5217.6086793489885;4909.852704418873;4943.28322393251;4956.058348913695;4984.7258400788305;5001;4992.450830408652;5030.2957585938;5044.949550504448;5051.166557517969;5057.519535644108;4981.551660350586;5001.349824522788;5004.249362595567;5036.194208926554;5074.16276314404;5020.171671674206;5025.899482010339;5027.497250275016;5032.874178145576;5076.289319495274;4346.087321662486;4347.1740369330355;4348.717333373321;4351.151525452507;4397.230484665235;3720.088578298334;3727.161388687578;3732.570754363229;3733.8506459741848;3734.57052438973;4349.673774466864;4354.4992325882295;4354.847580334736;4388.232059389594;4389.1660584488845;5079.974600126976;5083.567896728746;5086.974565127225;5088.720120393414;5133.486651334912;4290;4299.613034826881;4337.7180483268485;4347.543507931685;4348.34774783785;4604.263317869125;4620.029793743284;4621.2837010263465;4661;4662.813487460531;4764.213904705684;4769.070031343994;4773.952260477489;4800.975995120002;4809;4907.263910413467;4966.900661986812;5018.42288136869;5112;5116.795328186831;4709.764511774466;4757.785899634594;4763.857084287477;4775.570198682135;4812.711237325771;4940.654154209171;4990.775415106256;4992.400911890596;5000.724960127228;5029;4832.323474713613;5006.1739812930455;5007.449180590059;5009.874753131201;5055.671381360347;4592.425946756655;4649.1863923812525;4677.812887484599;4679.485256621837;4742.07529531738;4700.576948074689;4703.529647035269;4706.176419126571;4736.715797052188;4753.073150735573;4640.582347588733;4689.976550117228;4690.061987602425;4704.9529504704515;4750.049990001944;4799.3760811095735;4803.1114243864995;4847.830325938575;4848.466668666373;4889.584385327287;4662.440507139165;4667.066586682745;4682.063587282625;4685.6251499879245;4766.713997160182 18:21:37 INFO - PROCESS | 4821 | |1;ext.html;537.5645726961162;544.170140535683;548.6790227716785;562.0669688317391;567.9318481782187;29939.850300748494;31990;42560.14879702404;43483;43771.56228437715;41790.582094179124;41864.58135418645;41900.79049604748;41963.58036419635;41977.28363461847;7718.691252349913;7891.053073631172;7895.565743884084;7909.920900791005;7921.04947406312;7549.660265288076;7747.3027427531515;9929.851052234222;10179.491025448735;10180.338278011943;10265.692029239097;10305.072543471124;10318.12295954841;10322.122619577345;10338.172946164288;10180.541875615616;10246.9487652562;10260.692179234598;10264.589416423334;10268.897311026869;10009.399436033862;10075.193984481224;10078.496075196284;10082.24383171265;10084.747881302952;38860.22279554393;39292.21415571673;39322.60677393218;39354.40968385476;39432.60567394318;3545.556805399325;3550.14796448853;3557.8221088945447;3575.4279315309686;3576.2311103112957;28426.28934276639;28973.565396518858;29071.273218169503;29107.126786196448;29234;3491.94762078569;3548.929021419557;3604.7656902301264;3718.3492888744363;3725.310817498744;237159.81420092963;238304;238957;239239;240641;53321.7333913328;53625;53646;53738.731306344;53753.1937020945;54806.725966369915;54883.72558137184;55228;55291.44708552863;55336.44663553313;14548.127112373291;14783.778243326358;14895;14898.627534311729;14911.627209319853;10161.949190254001;10618.681439556825;10639.202059845542;10648.094911932572;10669.78660426787;13905.513307034205;15298.847011529742;15533.689326213638;15731.741460683117;16305.918470407809;5852.941470585325;5893.469587737039;5894.616849904743;5955.642661440327;5989.790357337472;805.959702014897;824.8803923431069;828.3125006244923;839.5550358310105;849.3460035772421;27391;28081.71918280832;28341;28826;28873.711262887104;899.298547133224;944.641036406175;948.814981078683;958.7075941837667;975.3708857786688;14371.496997605034;14839.92580037093;15014.47449339268;15243.390264389522;15290.923545382424;343890;345957;345992;346281;348242;49657;49860.50139498631;49872.750636247314;49894.257930655345;49895.75052124716;1191.338806962127;1235.14774805384;1250.581055346451;1250.8874201321926;1253.724180680249;26148.86925565436;27110.457790844466;27309.180724578295;27586.72413275922;27976;41941.370879436225;42458.36312455254;42495.93760156021;42522.36216456818;42591.14817703691;273668;275609;276087;276132;276472;31966.840165799953;33084;35111;36438.63561364352;36464.27071458609;39714.801425992686;41749.37375939303;42578.361324579535;46236.53763462322;47766.283505748135;35363.46954795731;36072.27855442929;36964.26071478609;37844.62155378521;38269.4259586112;36194.819025904704;36280.274394511434;36316.27367452689;36587.45118823273;37089;35683.28633427265;36461.27077458383;36641.81679091587;36942;36975.44536832003;28385.43229135447;30072.69927300699;30168;30180.54729179108;30590.84704576463;21198.682019769407;21407.357779266647;21504.56990860143;21559.24542641;21656.242031528822 18:21:37 INFO - PROCESS | 4821 | |2;jquery.html;347.88345904122116;353.755908423188;361.78473808084186;363.06872871085665;370.9703223742099;13628.114172578787;16345.26446309916;17140.571485712862;18444.170012349445;18720;18194.363197288098;18242.63514729708;18325.633487330244;18370.540736481595;18394.540136496562;4456.019675671355;4664.953350466486;4686.297055441677;4742.407199100113;4753.667243292971;3682.171511409938;3816.4466152407886;4706.599939005188;4743.098811225873;4747.0505898820375;3800.714946379019;3881.514810648669;3893.6301051400073;6090.725917333709;6148.231471066117;6164.198654174974;6165.907511387333;6167.074938759222;6174.876502469925;6191.226096737908;4741.099191153669;4963.578095861856;4992.900141997176;5067.49325067494;5075.213341932006;11919.344436056013;11949.402529873472;11998.880011199952;12116.27302361861;12167.51329946801;2156.633372326708;2177.379446857654;2268.6143355629583;2408.650745641872;2442.3771938155655;8868.423552469068;8885.289176865836;9201.585928633227;9356.251499879992;13275.734485310337;1753.184769082383;1799.6760583095038;1819.3086627081625;1821.4080423862165;1827.7623908891892;75602;82069.58965205196;83465.58267208625;83556;85900;9931.801363972825;9985.251106167067;9992.600295988079;10291.279610427198;10359.689209323733;13693.246871421969;13695.931520342534;13704.314784260747;13739.106958047694;13830.792538111937;7172.246914074051;7302.890456643155;7307.598082105429;7481.102267727908;7572.356349710226;6949.409300209438;7023.262557431498;7165.856682866313;7182.281771822881;7199.784006479814;4036.1725846201457;4155.625993660525;4203.747775133441;4207.957920420818;4346.543612920661;1675.5559776659195;1784.3754685859844;1785.214505617524;1809.1316168239055;1822.8086050964457;916.5600511754262;933.0948979489884;937.3906960475746;949.3591825517748;959.0936564946059;17038.403655871985;17567.209475573374;18063.63872722538;18067.27730890776;18458.892466452056;892.723255790707;898.7213963671282;916.0564618443015;928.6935311347239;938.0478814003792;9316.254699624014;9954.402735835873;9998.80002399948;10194;10201.355612384357;509904;516265;525167;526160;539707;94690.5265473682;95267;95340.52329738445;99765.53165322589;100016;883.8497455432592;915.3043686797948;934.2152591822901;936.1247233836249;937.6624415210518;14934.925325373304;15354;15612.21938903073;15642;15907.761383579023;39912.80043599763;40481.547029826106;41009.794951025055;41388.379174311805;42977.78511107424;50283.74858125686;52515;54415.72792136014;55512.444875550726;56821;5100.056489549445;5893.469587737124;7498.287662672037;7622.047244094489;7794.805129871798;11855.755145709922;12076.275423474619;12331.383430828244;12421.503139874303;12440.315782632042;10783.784324313312;10891;10914.672559823219;10931;11136.498857551267;10611;10653.30753501051;10662.25364224528;10805.729856753644;11114.333140011626;10770.13838892871;10920.50857711389;10949.890501095206;10997.890021100007;11486.597969071043;7511.5868627223845;7606.543607383574;7624.923750762421;7838.804029899298;7872.488288261245;7866.8819967701675;10532;10532.736071671448;10701;10935.61725339641 18:21:37 INFO - PROCESS | 4821 | |3;mootools.html;1313.901457390696;1330.1752913193818;1330.7737684593612;1340.7385559815839;1342.5905098944818;6220.377962203777;6608.504362172832;6621.073049773024;6629.071929929808;6629.171353580802;5693.146028095777;5878.265216847894;6213.068039794033;6381.521385896053;6543.280239173699;2026.7466566679202;2057.567910738739;2061.5361543652707;2061.907214175366;2063.5460198756323;1986.692062730279;2074.502119491319;2077.2625717870164;2090.1221486975505;2095.2352391377112;1759.3578343904446;1807.3945228348518;2002.469345623411;2056.362527616436;2060.5054786851124;2028.0873606876964;2035.8167764901082;2037.836973042153;2042.7344445222175;2051.138521820839;1979.9703004454946;2001.449601359623;2002.98998505008;2006.147387360366;2059.660156074246;6562.146920900253;6634.601923884534;6640.667966601651;6640.933590664128;6642.169728783902;4108.404281379213;4227.387028880795;4227.788610569459;4235.343521754133;4241.851535196284;2614.2810727050023;2746.8901243950218;2814.3949050954143;2840.0201930334;2878.7121287871255;1049.4647729657854;1058.317385286486;1089.3790539392548;1100.9339439633648;1123;8419.915800842036;8446.704365347245;8464.576771161417;8467.068622451587;8468.915310846874;3537.2218112015316;3734.8879533614036;3770.924581508409;3800.574784455829;3834.1373191031794;7436.219196984347;7451.3293803557945;7452.21751716062;7454.2545745426105;7458.4033277337685;4213.494380674339;4234.3860140279485;4296.2911119664905;4296.677749168824;4298.09739954613;3808.21931504041;3913.2369188008634;3920.764754114762;3922.7057970652313;3928;3263.282077942849;3311.089450401159;3349.363620912019;3362.6549380247607;3383.780054296463;1105.4141305108305;1116.9162312826409;1123.5225029362653;1135.2961164078324;1139.0545846947014;762.7864198024562;777.9883301750478;783.6512751825497;797.2983774278714;801.0587559617543;4567.954320456819;4654.604358629555;4707.599854012447;4724.503927087606;4831.251156070814;510.8978204359143;511.8490045436605;520.5783315514445;521.317074632233;521.3795583255913;3140.120766185472;3187.2828613561765;3217.8391080445886;3231.822249776285;3296.8846090386724;7175.497715159889;7303.744368947061;7431.405487560982;7458.738944137038;7687.154413014564;7643.961780191064;7658.425618078666;7659.770206893802;7693.538387696756;7695.307422332017;6132.662703551258;6134.631922084689;6276.46650034743;6324.747010119544;6332.271788744261;4893.951060489349;4979.178435558238;4989.476105008922;5025.145725226647;5058.367704036996;7142.071530700976;7145.249748776511;7148.17795953472;7152.392046675883;7153.177384600837;6958.582485050913;7138.072050633385;7157.355837974607;7223.674934628059;7313.926860731537;5802.970985145047;5830.941690583039;5856.619319744203;5908.970455147869;5936.703164841654;6208.782692605737;6210.937890621036;6234.438900498977;6237.781677641261;6256.155419018374;5756.913646295226;5828.795992140254;5834.94165058344;5894.911576326444;5967.343592204767;5682.289713785777;5738.282714660668;5742.741576628978;5769.567282453749;5807.274090738658;5139.640225184201;5181.222816577543;5216.426193118678;5273.709945953009;5340.786368545215;4211.705180637448;4220.092680073799;4287.271163902206;4336.219480493542;4410.933835992399;4327.459067616548;4350.499692535399;4370.825166993284;4389.209942210305;4454.933176002298 18:21:37 INFO - PROCESS | 4821 | |4;prototype.html;304.75771761449676;330.0461665785879;330.73045467943626;339.2214866880509;340.53516949364126;4656.813727450902;4724.38582984212;4819.903601927959;4827.300041493986;4836.685615434994;4520.2767557190855;4760.309755085511;4760.381150450446;4771.546703063212;4796.424429068508;3663.5970043295283;3722.7952462614544;3727.161388687537;3754.9436758448646;3762.6237376262293;3566.286742651467;3650.6166852480505;3684.8710295139676;3746.4193050077283;3750.3811871041244;3709.1283548366114;3716.24997501848;3720.962790372089;3721.9627803722024;3726.0871086583825;3701.68535674468;3711.740178187528;3723.547816351625;3730.2725968436166;3743.513343265358;3095.7987730797427;3137.3568418474156;3190.170555655523;3244.5944256967878;3290.901272961815;4674.415698037746;4683.1336202802595;4697.084068606623;4701.576858082789;4706.599939005155;4204.633494114394;4233.7883105844585;4233.894152646178;4237.4491316129015;4240.618344349024;4717.9292310615365;4965.79022335061;5022.623303252234;5029.723365214948;5038.823641172541;3564.4475106358295;3575.5888072871694;3575.94636080459;3583.7670551414076;3591.1560783215923;5291.86770330741;5332.493413125708;5333.839984800462;5341.412444631087;5341.706206158621;3636.9818150909077;3643.180284435981;3649.94525082124;3658.63413658632;3669.981650091786;3481.2167262365765;3637.3634613942454;3648.179159689049;3654.4152935530187;3665.780053196817;4006.4791577094793;4074.8370065197655;4093.815778289984;4109.589041095866;4122.670186385142;4876.292937524039;4966.230234313687;5046.6719663221775;5048.829003528518;5061.873453163626;1229;1236.0544183699476;1237.7586370657816;1239.132607174981;1249.6563445052502;806.1253539909147;835.5863847395578;843.5149788871423;859.8667206582865;867.757028032152;4313.396124542566;4315.805788739514;4321.80186661868;4326.610605045561;4334.891627709269;5035.723035233097;5066.873328166826;5070.61970352225;5073.644844860898;5101.8724531886255;4233.026403927114;4242.257604919127;4257.957420425818;4262.424572682648;4273.230818452646;2512.6859142607173;2665.280374298967;2694.8922043118055;2698.2984424049496;2701.7568418842397;4718.363020992122;4743.525647435298;4755.025219829927;4763.69036012658;4798.5441383068555;4921.384826896638;5190.8183213587345;5211.983663185642;5212.801055757197;5252.2384254282915;4686.297055441711;4714.269288260325;4737.8341758039;4754.429468463806;4757.738324392191;5015.172496538176;5045.192769156917;5062;5066.566754090171;5081.618878584195;4859.805607775649;4969.6272779542405;5010.423801262901;5018.673786203885;5029.278334032862;4840.588549973291;4874.731889746098;4883.169861123689;4884.462709102067;4899.706017638953;3618.692411145082;3638.9636103638622;3646.690031347365;3658.414653655402;3664.5052917855746;5076.568491678248;5177.223416487406;5212.791488340424;5219.973900130627;5224.791008359623;4807.687500312469;4819.95180048195;4829.2997515359375;4829.830955916526;4842.806287748451;4804.903901921872;4824.831130910401;4826.0830442215865;4829.13075646387;4849.684770489906;4721.244600863845;4839.733814640229;4847.587955023862;4855.927161092516;4873.269009648439;4914.385701787277;4924.409070911371;4927.334809800631;4933.447917499395;4946.163527038129;4813.772488015534;4830;4831.468538460696;4835.178019736723;4840.273958906192 18:21:37 INFO - PROCESS | 4821 | |5;yui.html;630.9148264984232;656.1764984943898;667.6194569095614;687.5565260407037;688.9931100688991;11751.706207344821;12405.317707526083;12423.192492487993;12425.378731063445;12427.13010089294;13240.801387979189;13287.202767833936;13350.465981360734;13395.665108372295;13395.665108372295;645.5513418174371;658.3219284137347;776.7980325115454;791.1376599506536;806.1978331560076;792.4492477727953;810.5784991804259;811.0956283743612;811.253646645085;811.7767613906163;865.2040123086744;867.2758246863845;867.7526904832124;868.0494858130337;868.1839071273015;679.5786612300356;693.0505207865257;817.7383237364046;866.1684782608703;866.3978534918202;616.3805375597541;624.0140577886928;624.0234033737187;624.4692011789988;625.1591609285504;12670.936645316806;12684.42920068599;12714.809277860746;12714.936425317906;12739.235645861278;12929.482820687255;12982.675433114153;13013.21920684762;13247.867521324759;13275.734485310337;10688.679339619825;10768.353898766021;10955.233133680646;11168.274062185932;11238.606648767336;4149.356849688273;4162.875113746562;4163.666906647461;4321.67587430944;4322.697411181219;62842;64053;64762.67618661924;65305.3469465304;65876.3412365875;10415.895841041644;10538.188206172346;10554.788904221872;10724.571017159382;10726.892731072589;20244.392668219978;20629.071691773905;20661.483462913548;20672.069756860976;21004.684929726067;6538.346165383424;6559.737610495622;6629.668516574152;6643;6643.330528349467;3530.947035794465;3630.2739452109686;3643.5809881863925;3678.8367565849717;3699.852005919733;2744.4922689302543;2794.902178423745;2809.662840459158;2822.802403831712;2832;496.55310220800845;496.65482489669336;498.47410981414953;503.0969409909222;508.3442359356463;565.8868226354663;572.1160806553801;577.4369989260438;580.4166812353558;581.340178896955;8295.336373090138;8458.534780587126;8569.871451928226;8640;8738.956305218433;581.7782656421481;586.6919867069737;586.762361243697;596.4602035158189;617.0867116667357;3900.941485877714;4114.238865809834;4139.0480189555965;4140.627343539036;4275.251830929575;63701.362986369546;65631.67184164049;66384.33615663878;67145.66427167832;67174.66412667905;46856;56695.716521417125;58271;59463.405365945786;60405.697971509864;51679.22481162786;51923.74038129861;52024.73987630037;52274;52382.21426678603;7661.272179142972;7867.134615192439;7926.920730792849;8037.678492860454;8126.065502467292;35786.64213357833;35842.82078589591;36400.08999775027;36575.63424365722;37458.25083498369;40809.387859181545;42153.78923105365;43378.132437351705;43817.34273985829;45862.77068614635;9545.188658964065;9641.228701703707;9722.75693107678;9915.950420247853;9966.651167209113;10068.546915388972;10314.226433017704;10420.166386688896;10421.895781042092;10437.791244175225;10032.197424206191;10153.39079655223;10218.489075546342;10664.680059598224;11246.040126715867;10913.345199288067;11372.601958931398;11560.132990025613;11965.700857478632;12048.638540843789;12262;12559.372031398576;13055.869441305846;13428.498801293796;13512.121712088683;9516.524173791144;9569.50686065721;9832.754181145528;9844.409335439897;9917.603295868374;10080.546375412978;10252.846207306748;10369.170466362824;10455.163586912877;10497.842532362174 18:21:37 INFO - PROCESS | 4821 | __end_tp_report 18:21:37 INFO - PROCESS | 4821 | __start_cc_report 18:21:37 INFO - PROCESS | 4821 | _x_x_mozilla_cycle_collect,951 18:21:37 INFO - PROCESS | 4821 | __end_cc_report 18:21:37 INFO - PROCESS | 4821 | __startTimestamp1483669297753__endTimestamp 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | ------- Summary: start ------- 18:21:37 INFO - PROCESS | 4821 | Number of tests: 6 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | [#0] dojo.html Cycles:165 Average:4637.81 Median:4727.57 stddev:481.19 (10.2%) stddev-sans-first:454.90 18:21:37 INFO - PROCESS | 4821 | Values: 2584.3 2598.4 2603.9 2624.3 2625.3 4610.2 4727.6 4759.6 4763.3 4768.0 4643.9 4703.8 4724.7 4752.3 4802.9 4316.8 4317.3 4340.2 4367.3 4420.2 4007.2 4329.1 4379.5 4412.2 4445.6 4276.1 4308.4 4322.6 4336.5 4431.2 4341.2 4351.2 4356.3 4358.5 4359.8 4326.4 4329.8 4332.4 4337.9 4341.9 4635.0 4659.5 4700.3 4721.2 4781.2 5016.1 5026.0 5029.8 5036.0 5082.9 5046.5 5055.2 5071.5 5077.4 5111.2 4552.1 4571.7 4590.9 4730.2 4784.3 5013.7 5096.8 5135.1 5173.9 5217.6 4909.9 4943.3 4956.1 4984.7 5001.0 4992.5 5030.3 5044.9 5051.2 5057.5 4981.6 5001.3 5004.2 5036.2 5074.2 5020.2 5025.9 5027.5 5032.9 5076.3 4346.1 4347.2 4348.7 4351.2 4397.2 3720.1 3727.2 3732.6 3733.9 3734.6 4349.7 4354.5 4354.8 4388.2 4389.2 5080.0 5083.6 5087.0 5088.7 5133.5 4290.0 4299.6 4337.7 4347.5 4348.3 4604.3 4620.0 4621.3 4661.0 4662.8 4764.2 4769.1 4774.0 4801.0 4809.0 4907.3 4966.9 5018.4 5112.0 5116.8 4709.8 4757.8 4763.9 4775.6 4812.7 4940.7 4990.8 4992.4 5000.7 5029.0 4832.3 5006.2 5007.4 5009.9 5055.7 4592.4 4649.2 4677.8 4679.5 4742.1 4700.6 4703.5 4706.2 4736.7 4753.1 4640.6 4690.0 4690.1 4705.0 4750.0 4799.4 4803.1 4847.8 4848.5 4889.6 4662.4 4667.1 4682.1 4685.6 4766.7 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | [#1] ext.html Cycles:180 Average:45136.46 Median:3549.54 stddev:75971.00 (2140.3%) stddev-sans-first:76110.32 18:21:37 INFO - PROCESS | 4821 | Values: 537.6 544.2 548.7 562.1 567.9 29939.9 31990.0 42560.1 43483.0 43771.6 41790.6 41864.6 41900.8 41963.6 41977.3 7718.7 7891.1 7895.6 7909.9 7921.0 7549.7 7747.3 9929.9 10179.5 10180.3 10265.7 10305.1 10318.1 10322.1 10338.2 10180.5 10246.9 10260.7 10264.6 10268.9 10009.4 10075.2 10078.5 10082.2 10084.7 38860.2 39292.2 39322.6 39354.4 39432.6 3545.6 3550.1 3557.8 3575.4 3576.2 28426.3 28973.6 29071.3 29107.1 29234.0 3491.9 3548.9 3604.8 3718.3 3725.3 237159.8 238304.0 238957.0 239239.0 240641.0 53321.7 53625.0 53646.0 53738.7 53753.2 54806.7 54883.7 55228.0 55291.4 55336.4 14548.1 14783.8 14895.0 14898.6 14911.6 10161.9 10618.7 10639.2 10648.1 10669.8 13905.5 15298.8 15533.7 15731.7 16305.9 5852.9 5893.5 5894.6 5955.6 5989.8 806.0 824.9 828.3 839.6 849.3 27391.0 28081.7 28341.0 28826.0 28873.7 899.3 944.6 948.8 958.7 975.4 14371.5 14839.9 15014.5 15243.4 15290.9 343890.0 345957.0 345992.0 346281.0 348242.0 49657.0 49860.5 49872.8 49894.3 49895.8 1191.3 1235.1 1250.6 1250.9 1253.7 26148.9 27110.5 27309.2 27586.7 27976.0 41941.4 42458.4 42495.9 42522.4 42591.1 273668.0 275609.0 276087.0 276132.0 276472.0 31966.8 33084.0 35111.0 36438.6 36464.3 39714.8 41749.4 42578.4 46236.5 47766.3 35363.5 36072.3 36964.3 37844.6 38269.4 36194.8 36280.3 36316.3 36587.5 37089.0 35683.3 36461.3 36641.8 36942.0 36975.4 28385.4 30072.7 30168.0 30180.5 30590.8 21198.7 21407.4 21504.6 21559.2 21656.2 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | [#2] jquery.html Cycles:180 Average:28998.94 Median:4179.69 stddev:86424.18 (2067.7%) stddev-sans-first:86639.84 18:21:37 INFO - PROCESS | 4821 | Values: 347.9 353.8 361.8 363.1 371.0 13628.1 16345.3 17140.6 18444.2 18720.0 18194.4 18242.6 18325.6 18370.5 18394.5 4456.0 4665.0 4686.3 4742.4 4753.7 3682.2 3816.4 4706.6 4743.1 4747.1 3800.7 3881.5 3893.6 6090.7 6148.2 6164.2 6165.9 6167.1 6174.9 6191.2 4741.1 4963.6 4992.9 5067.5 5075.2 11919.3 11949.4 11998.9 12116.3 12167.5 2156.6 2177.4 2268.6 2408.7 2442.4 8868.4 8885.3 9201.6 9356.3 13275.7 1753.2 1799.7 1819.3 1821.4 1827.8 75602.0 82069.6 83465.6 83556.0 85900.0 9931.8 9985.3 9992.6 10291.3 10359.7 13693.2 13695.9 13704.3 13739.1 13830.8 7172.2 7302.9 7307.6 7481.1 7572.4 6949.4 7023.3 7165.9 7182.3 7199.8 4036.2 4155.6 4203.7 4208.0 4346.5 1675.6 1784.4 1785.2 1809.1 1822.8 916.6 933.1 937.4 949.4 959.1 17038.4 17567.2 18063.6 18067.3 18458.9 892.7 898.7 916.1 928.7 938.0 9316.3 9954.4 9998.8 10194.0 10201.4 509904.0 516265.0 525167.0 526160.0 539707.0 94690.5 95267.0 95340.5 99765.5 100016.0 883.8 915.3 934.2 936.1 937.7 14934.9 15354.0 15612.2 15642.0 15907.8 39912.8 40481.5 41009.8 41388.4 42977.8 50283.7 52515.0 54415.7 55512.4 56821.0 5100.1 5893.5 7498.3 7622.0 7794.8 11855.8 12076.3 12331.4 12421.5 12440.3 10783.8 10891.0 10914.7 10931.0 11136.5 10611.0 10653.3 10662.3 10805.7 11114.3 10770.1 10920.5 10949.9 10997.9 11486.6 7511.6 7606.5 7624.9 7838.8 7872.5 7866.9 10532.0 10532.7 10701.0 10935.6 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | [#3] mootools.html Cycles:180 Average:4403.18 Median:4936.56 stddev:2248.28 (45.5%) stddev-sans-first:2242.59 18:21:37 INFO - PROCESS | 4821 | Values: 1313.9 1330.2 1330.8 1340.7 1342.6 6220.4 6608.5 6621.1 6629.1 6629.2 5693.1 5878.3 6213.1 6381.5 6543.3 2026.7 2057.6 2061.5 2061.9 2063.5 1986.7 2074.5 2077.3 2090.1 2095.2 1759.4 1807.4 2002.5 2056.4 2060.5 2028.1 2035.8 2037.8 2042.7 2051.1 1980.0 2001.4 2003.0 2006.1 2059.7 6562.1 6634.6 6640.7 6640.9 6642.2 4108.4 4227.4 4227.8 4235.3 4241.9 2614.3 2746.9 2814.4 2840.0 2878.7 1049.5 1058.3 1089.4 1100.9 1123.0 8419.9 8446.7 8464.6 8467.1 8468.9 3537.2 3734.9 3770.9 3800.6 3834.1 7436.2 7451.3 7452.2 7454.3 7458.4 4213.5 4234.4 4296.3 4296.7 4298.1 3808.2 3913.2 3920.8 3922.7 3928.0 3263.3 3311.1 3349.4 3362.7 3383.8 1105.4 1116.9 1123.5 1135.3 1139.1 762.8 778.0 783.7 797.3 801.1 4568.0 4654.6 4707.6 4724.5 4831.3 510.9 511.8 520.6 521.3 521.4 3140.1 3187.3 3217.8 3231.8 3296.9 7175.5 7303.7 7431.4 7458.7 7687.2 7644.0 7658.4 7659.8 7693.5 7695.3 6132.7 6134.6 6276.5 6324.7 6332.3 4894.0 4979.2 4989.5 5025.1 5058.4 7142.1 7145.2 7148.2 7152.4 7153.2 6958.6 7138.1 7157.4 7223.7 7313.9 5803.0 5830.9 5856.6 5909.0 5936.7 6208.8 6210.9 6234.4 6237.8 6256.2 5756.9 5828.8 5834.9 5894.9 5967.3 5682.3 5738.3 5742.7 5769.6 5807.3 5139.6 5181.2 5216.4 5273.7 5340.8 4211.7 4220.1 4287.3 4336.2 4410.9 4327.5 4350.5 4370.8 4389.2 4454.9 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | [#4] prototype.html Cycles:180 Average:4111.06 Median:4719.80 stddev:1193.69 (25.3%) stddev-sans-first:1162.35 18:21:37 INFO - PROCESS | 4821 | Values: 304.8 330.0 330.7 339.2 340.5 4656.8 4724.4 4819.9 4827.3 4836.7 4520.3 4760.3 4760.4 4771.5 4796.4 3663.6 3722.8 3727.2 3754.9 3762.6 3566.3 3650.6 3684.9 3746.4 3750.4 3709.1 3716.2 3721.0 3722.0 3726.1 3701.7 3711.7 3723.5 3730.3 3743.5 3095.8 3137.4 3190.2 3244.6 3290.9 4674.4 4683.1 4697.1 4701.6 4706.6 4204.6 4233.8 4233.9 4237.4 4240.6 4717.9 4965.8 5022.6 5029.7 5038.8 3564.4 3575.6 3575.9 3583.8 3591.2 5291.9 5332.5 5333.8 5341.4 5341.7 3637.0 3643.2 3649.9 3658.6 3670.0 3481.2 3637.4 3648.2 3654.4 3665.8 4006.5 4074.8 4093.8 4109.6 4122.7 4876.3 4966.2 5046.7 5048.8 5061.9 1229.0 1236.1 1237.8 1239.1 1249.7 806.1 835.6 843.5 859.9 867.8 4313.4 4315.8 4321.8 4326.6 4334.9 5035.7 5066.9 5070.6 5073.6 5101.9 4233.0 4242.3 4258.0 4262.4 4273.2 2512.7 2665.3 2694.9 2698.3 2701.8 4718.4 4743.5 4755.0 4763.7 4798.5 4921.4 5190.8 5212.0 5212.8 5252.2 4686.3 4714.3 4737.8 4754.4 4757.7 5015.2 5045.2 5062.0 5066.6 5081.6 4859.8 4969.6 5010.4 5018.7 5029.3 4840.6 4874.7 4883.2 4884.5 4899.7 3618.7 3639.0 3646.7 3658.4 3664.5 5076.6 5177.2 5212.8 5220.0 5224.8 4807.7 4820.0 4829.3 4829.8 4842.8 4804.9 4824.8 4826.1 4829.1 4849.7 4721.2 4839.7 4847.6 4855.9 4873.3 4914.4 4924.4 4927.3 4933.4 4946.2 4813.8 4830.0 4831.5 4835.2 4840.3 18:21:37 INFO - PROCESS | 4821 | 18:21:37 INFO - PROCESS | 4821 | [#5] yui.html Cycles:180 Average:14759.84 Median:23676.28 stddev:18433.97 (77.9%) stddev-sans-first:18455.15 18:21:37 INFO - PROCESS | 4821 | Values: 630.9 656.2 667.6 687.6 689.0 11751.7 12405.3 12423.2 12425.4 12427.1 13240.8 13287.2 13350.5 13395.7 13395.7 645.6 658.3 776.8 791.1 806.2 792.4 810.6 811.1 811.3 811.8 865.2 867.3 867.8 868.0 868.2 679.6 693.1 817.7 866.2 866.4 616.4 624.0 624.0 624.5 625.2 12670.9 12684.4 12714.8 12714.9 12739.2 12929.5 12982.7 13013.2 13247.9 13275.7 10688.7 10768.4 10955.2 11168.3 11238.6 4149.4 4162.9 4163.7 4321.7 4322.7 62842.0 64053.0 64762.7 65305.3 65876.3 10415.9 10538.2 10554.8 10724.6 10726.9 20244.4 20629.1 20661.5 20672.1 21004.7 6538.3 6559.7 6629.7 6643.0 6643.3 3530.9 3630.3 3643.6 3678.8 3699.9 2744.5 2794.9 2809.7 2822.8 2832.0 496.6 496.7 498.5 503.1 508.3 565.9 572.1 577.4 580.4 581.3 8295.3 8458.5 8569.9 8640.0 8739.0 581.8 586.7 586.8 596.5 617.1 3900.9 4114.2 4139.0 4140.6 4275.3 63701.4 65631.7 66384.3 67145.7 67174.7 46856.0 56695.7 58271.0 59463.4 60405.7 51679.2 51923.7 52024.7 52274.0 52382.2 7661.3 7867.1 7926.9 8037.7 8126.1 35786.6 35842.8 36400.1 36575.6 37458.3 40809.4 42153.8 43378.1 43817.3 45862.8 9545.2 9641.2 9722.8 9916.0 9966.7 10068.5 10314.2 10420.2 10421.9 10437.8 10032.2 10153.4 10218.5 10664.7 11246.0 10913.3 11372.6 11560.1 11965.7 12048.6 12262.0 12559.4 13055.9 13428.5 13512.1 9516.5 9569.5 9832.8 9844.4 9917.6 10080.5 10252.8 10369.2 10455.2 10497.8 18:21:37 INFO - PROCESS | 4821 | -------- Summary: end -------- 18:21:37 INFO - PROCESS | 4821 | 18:21:38 INFO - TEST-INFO | 4821: exit 0 18:21:38 INFO - TEST-OK | dromaeo_css | took 1121508ms 18:21:38 INFO - TEST-START | kraken 18:21:38 INFO - operating with platform_type : linux_ 18:21:38 INFO - Initialising browser for kraken test... 18:21:38 INFO - TEST-INFO | started process 5127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNceCe/profile http://localhost:39969/getInfo.html) 18:21:39 INFO - PROCESS | 5127 | 1483669299213 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 18:21:42 INFO - PROCESS | 5127 | __metrics Screen width/height:1600/1200 18:21:42 INFO - PROCESS | 5127 | colorDepth:24 18:21:42 INFO - PROCESS | 5127 | Browser inner width/height: 1024/697 18:21:42 INFO - PROCESS | 5127 | __metrics 18:21:43 INFO - PROCESS | 5127 | 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" 18:21:43 INFO - PROCESS | 5127 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:21:44 INFO - PROCESS | 5127 | console.error: PushService: 18:21:44 INFO - PROCESS | 5127 | stateChangeProcessEnqueue: Error transitioning state 18:21:44 INFO - PROCESS | 5127 | UnknownError 18:21:44 INFO - TEST-INFO | 5127: exit 0 18:21:44 INFO - Browser initialized. 18:21:44 INFO - Running cycle 1/1 for kraken test... 18:21:44 INFO - TEST-INFO | started process 5213 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNceCe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:56 INFO - PROCESS | 5213 | RSS: Main: 152023040 18:21:56 INFO - PROCESS | 5213 | 18:21:59 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39969/tests/kraken/driver.html?testName=audio-beat-detection) 18:22:00 INFO - PROCESS | 5213 | RSS: Main: 206217216 18:22:00 INFO - PROCESS | 5213 | 18:22:03 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39969/tests/kraken/driver.html?testName=audio-dft) 18:22:03 INFO - PROCESS | 5213 | RSS: Main: 185630720 18:22:03 INFO - PROCESS | 5213 | 18:22:06 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39969/tests/kraken/driver.html?testName=audio-fft) 18:22:07 INFO - PROCESS | 5213 | RSS: Main: 268378112 18:22:07 INFO - PROCESS | 5213 | 18:22:09 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39969/tests/kraken/driver.html?testName=audio-oscillator) 18:22:10 INFO - PROCESS | 5213 | RSS: Main: 178331648 18:22:10 INFO - PROCESS | 5213 | 18:22:12 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:22:13 INFO - PROCESS | 5213 | RSS: Main: 182558720 18:22:13 INFO - PROCESS | 5213 | 18:22:16 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39969/tests/kraken/driver.html?testName=imaging-darkroom) 18:22:16 INFO - PROCESS | 5213 | RSS: Main: 222011392 18:22:16 INFO - PROCESS | 5213 | 18:22:20 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39969/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:20 INFO - PROCESS | 5213 | RSS: Main: 216018944 18:22:20 INFO - PROCESS | 5213 | 18:22:23 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39969/tests/kraken/driver.html?testName=json-parse-financial) 18:22:24 INFO - PROCESS | 5213 | RSS: Main: 217337856 18:22:24 INFO - PROCESS | 5213 | 18:22:26 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:26 INFO - PROCESS | 5213 | RSS: Main: 181391360 18:22:26 INFO - PROCESS | 5213 | 18:22:28 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:29 INFO - PROCESS | 5213 | RSS: Main: 188813312 18:22:29 INFO - PROCESS | 5213 | 18:22:31 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:32 INFO - PROCESS | 5213 | RSS: Main: 196747264 18:22:32 INFO - PROCESS | 5213 | 18:22:34 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:35 INFO - PROCESS | 5213 | RSS: Main: 207060992 18:22:35 INFO - PROCESS | 5213 | 18:22:38 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:39 INFO - PROCESS | 5213 | RSS: Main: 202514432 18:22:39 INFO - PROCESS | 5213 | 18:22:40 INFO - PROCESS | 5213 | Cycle 1(1): loaded http://localhost:39969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39969/tests/kraken/driver.html?testName=ai-astar) 18:22:41 INFO - PROCESS | 5213 | RSS: Main: 253820928 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | __start_tp_report 18:22:41 INFO - PROCESS | 5213 | _x_x_mozilla_page_load 18:22:41 INFO - PROCESS | 5213 | _x_x_mozilla_page_load_details 18:22:41 INFO - PROCESS | 5213 | |i|pagename|runs| 18:22:41 INFO - PROCESS | 5213 | |0;ai-astar;106;114;115;114;114;114;114;114;115;114 18:22:41 INFO - PROCESS | 5213 | |1;audio-beat-detection;157;125;123;160;129;129;123;125;150;124 18:22:41 INFO - PROCESS | 5213 | |2;audio-dft;161;161;160;161;161;161;161;161;161;160 18:22:41 INFO - PROCESS | 5213 | |3;audio-fft;75;77;77;108;77;77;76;76;92;76 18:22:41 INFO - PROCESS | 5213 | |4;audio-oscillator;84;85;110;85;85;84;100;85;85;102 18:22:41 INFO - PROCESS | 5213 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 18:22:41 INFO - PROCESS | 5213 | |6;imaging-darkroom;148;149;148;148;149;149;149;150;148;149 18:22:41 INFO - PROCESS | 5213 | |7;imaging-desaturate;96;95;95;95;95;95;95;96;95;95 18:22:41 INFO - PROCESS | 5213 | |8;json-parse-financial;66;60;60;61;62;63;74;63;61;62 18:22:41 INFO - PROCESS | 5213 | |9;json-stringify-tinderbox;69;68;67;66;67;67;67;67;67;68 18:22:41 INFO - PROCESS | 5213 | |10;stanford-crypto-aes;91;85;84;84;85;89;84;84;84;83 18:22:41 INFO - PROCESS | 5213 | |11;stanford-crypto-ccm;140;136;134;133;131;143;131;138;180;138 18:22:41 INFO - PROCESS | 5213 | |12;stanford-crypto-pbkdf2;176;174;178;174;175;176;175;173;173;173 18:22:41 INFO - PROCESS | 5213 | |13;stanford-crypto-sha256-iterative;68;70;74;68;67;67;68;70;67;66 18:22:41 INFO - PROCESS | 5213 | __end_tp_report 18:22:41 INFO - PROCESS | 5213 | __start_cc_report 18:22:41 INFO - PROCESS | 5213 | _x_x_mozilla_cycle_collect,1844 18:22:41 INFO - PROCESS | 5213 | __end_cc_report 18:22:41 INFO - PROCESS | 5213 | __startTimestamp1483669361221__endTimestamp 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | ------- Summary: start ------- 18:22:41 INFO - PROCESS | 5213 | Number of tests: 14 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.63 (2.3%) stddev-sans-first:0.44 18:22:41 INFO - PROCESS | 5213 | Values: 106.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 115.0 114.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:129.00 stddev:14.95 (11.6%) stddev-sans-first:13.46 18:22:41 INFO - PROCESS | 5213 | Values: 157.0 125.0 123.0 160.0 129.0 129.0 123.0 125.0 150.0 124.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 18:22:41 INFO - PROCESS | 5213 | Values: 161.0 161.0 160.0 161.0 161.0 161.0 161.0 161.0 161.0 160.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#3] audio-fft Cycles:10 Average:81.10 Median:77.00 stddev:10.67 (13.9%) stddev-sans-first:11.09 18:22:41 INFO - PROCESS | 5213 | Values: 75.0 77.0 77.0 108.0 77.0 77.0 76.0 76.0 92.0 76.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:85.00 stddev:9.65 (11.4%) stddev-sans-first:9.95 18:22:41 INFO - PROCESS | 5213 | Values: 84.0 85.0 110.0 85.0 85.0 84.0 100.0 85.0 85.0 102.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 18:22:41 INFO - PROCESS | 5213 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 18:22:41 INFO - PROCESS | 5213 | Values: 148.0 149.0 148.0 148.0 149.0 149.0 149.0 150.0 148.0 149.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:22:41 INFO - PROCESS | 5213 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:62.50 stddev:4.18 (6.7%) stddev-sans-first:4.31 18:22:41 INFO - PROCESS | 5213 | Values: 66.0 60.0 60.0 61.0 62.0 63.0 74.0 63.0 61.0 62.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.60 18:22:41 INFO - PROCESS | 5213 | Values: 69.0 68.0 67.0 66.0 67.0 67.0 67.0 67.0 67.0 68.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:84.50 stddev:2.58 (3.1%) stddev-sans-first:1.73 18:22:41 INFO - PROCESS | 5213 | Values: 91.0 85.0 84.0 84.0 85.0 89.0 84.0 84.0 84.0 83.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:138.00 stddev:14.45 (10.5%) stddev-sans-first:15.32 18:22:41 INFO - PROCESS | 5213 | Values: 140.0 136.0 134.0 133.0 131.0 143.0 131.0 138.0 180.0 138.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.00 stddev:1.64 (0.9%) stddev-sans-first:1.67 18:22:41 INFO - PROCESS | 5213 | Values: 176.0 174.0 178.0 174.0 175.0 176.0 175.0 173.0 173.0 173.0 18:22:41 INFO - PROCESS | 5213 | 18:22:41 INFO - PROCESS | 5213 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.32 (3.4%) stddev-sans-first:2.46 18:22:41 INFO - PROCESS | 5213 | Values: 68.0 70.0 74.0 68.0 67.0 67.0 68.0 70.0 67.0 66.0 18:22:41 INFO - PROCESS | 5213 | -------- Summary: end -------- 18:22:41 INFO - PROCESS | 5213 | 18:22:42 INFO - TEST-INFO | 5213: exit 0 18:22:42 INFO - TEST-OK | kraken | took 63755ms 18:22:42 INFO - SUITE-END | took 1185s 18:22:42 INFO - Completed test suite (00:19:45) 18:22:42 INFO - javascript benchmark 18:22:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2584.302238395633, 2598.3634009667635, 2603.921882343529, 2624.2914413108456, 2625.26492582077, 4610.193216187162, 4727.574518293353, 4759.571638552529, 4763.285507173926, 4768.046390721861, 4643.860684179472, 4703.811847526094, 4724.669273150881, 4752.334673145754, 4802.927956080662, 4316.784160791964, 4317.287647538153, 4340.240457919851, 4367.301231802912, 4420.248557745191, 4007.192396609312, 4329.134173165364, 4379.4525684289465, 4412.205802955466, 4445.644348452132, 4276.080642661812, 4308.439902812645, 4322.567743225683, 4336.479622445327, 4431.1802316571375, 4341.240282950471, 4351.238533256667, 4356.281213599752, 4358.4769827620885, 4359.760213188273, 4326.437563116775, 4329.7618630975285, 4332.350147477872, 4337.913241735179, 4341.869743907688, 4634.976825115887, 4659.464161621389, 4700.29495575663, 4721.244600863845, 4781.211100168468, 5016.147254966664, 5026.045051440251, 5029.7736601853, 5035.96762663653, 5082.923756143661, 5046.5205805448795, 5055.216441451544, 5071.518205770446, 5077.365329333833, 5111.2333150027425, 4552.135094332067, 4571.6799824012, 4590.862274131781, 4730.160628163714, 4784.33019377284, 5013.724245166478, 5096.821611243588, 5135.101357262464, 5173.948260517347, 5217.6086793489885, 4909.852704418873, 4943.28322393251, 4956.058348913695, 4984.7258400788305, 5001.0, 4992.450830408652, 5030.2957585938, 5044.949550504448, 5051.166557517969, 5057.519535644108, 4981.551660350586, 5001.349824522788, 5004.249362595567, 5036.194208926554, 5074.16276314404, 5020.171671674206, 5025.899482010339, 5027.497250275016, 5032.874178145576, 5076.289319495274, 4346.087321662486, 4347.1740369330355, 4348.717333373321, 4351.151525452507, 4397.230484665235, 3720.088578298334, 3727.161388687578, 3732.570754363229, 3733.8506459741848, 3734.57052438973, 4349.673774466864, 4354.4992325882295, 4354.847580334736, 4388.232059389594, 4389.1660584488845, 5079.974600126976, 5083.567896728746, 5086.974565127225, 5088.720120393414, 5133.486651334912, 4290.0, 4299.613034826881, 4337.7180483268485, 4347.543507931685, 4348.34774783785, 4604.263317869125, 4620.029793743284, 4621.2837010263465, 4661.0, 4662.813487460531, 4764.213904705684, 4769.070031343994, 4773.952260477489, 4800.975995120002, 4809.0, 4907.263910413467, 4966.900661986812, 5018.42288136869, 5112.0, 5116.795328186831, 4709.764511774466, 4757.785899634594, 4763.857084287477, 4775.570198682135, 4812.711237325771, 4940.654154209171, 4990.775415106256, 4992.400911890596, 5000.724960127228, 5029.0, 4832.323474713613, 5006.1739812930455, 5007.449180590059, 5009.874753131201, 5055.671381360347, 4592.425946756655, 4649.1863923812525, 4677.812887484599, 4679.485256621837, 4742.07529531738, 4700.576948074689, 4703.529647035269, 4706.176419126571, 4736.715797052188, 4753.073150735573, 4640.582347588733, 4689.976550117228, 4690.061987602425, 4704.9529504704515, 4750.049990001944, 4799.3760811095735, 4803.1114243864995, 4847.830325938575, 4848.466668666373, 4889.584385327287, 4662.440507139165, 4667.066586682745, 4682.063587282625, 4685.6251499879245, 4766.713997160182], "value": 4607.233956266064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.5645726961162, 544.170140535683, 548.6790227716785, 562.0669688317391, 567.9318481782187, 29939.850300748494, 31990.0, 42560.14879702404, 43483.0, 43771.56228437715, 41790.582094179124, 41864.58135418645, 41900.79049604748, 41963.58036419635, 41977.28363461847, 7718.691252349913, 7891.053073631172, 7895.565743884084, 7909.920900791005, 7921.04947406312, 7549.660265288076, 7747.3027427531515, 9929.851052234222, 10179.491025448735, 10180.338278011943, 10265.692029239097, 10305.072543471124, 10318.12295954841, 10322.122619577345, 10338.172946164288, 10180.541875615616, 10246.9487652562, 10260.692179234598, 10264.589416423334, 10268.897311026869, 10009.399436033862, 10075.193984481224, 10078.496075196284, 10082.24383171265, 10084.747881302952, 38860.22279554393, 39292.21415571673, 39322.60677393218, 39354.40968385476, 39432.60567394318, 3545.556805399325, 3550.14796448853, 3557.8221088945447, 3575.4279315309686, 3576.2311103112957, 28426.28934276639, 28973.565396518858, 29071.273218169503, 29107.126786196448, 29234.0, 3491.94762078569, 3548.929021419557, 3604.7656902301264, 3718.3492888744363, 3725.310817498744, 237159.81420092963, 238304.0, 238957.0, 239239.0, 240641.0, 53321.7333913328, 53625.0, 53646.0, 53738.731306344, 53753.1937020945, 54806.725966369915, 54883.72558137184, 55228.0, 55291.44708552863, 55336.44663553313, 14548.127112373291, 14783.778243326358, 14895.0, 14898.627534311729, 14911.627209319853, 10161.949190254001, 10618.681439556825, 10639.202059845542, 10648.094911932572, 10669.78660426787, 13905.513307034205, 15298.847011529742, 15533.689326213638, 15731.741460683117, 16305.918470407809, 5852.941470585325, 5893.469587737039, 5894.616849904743, 5955.642661440327, 5989.790357337472, 805.959702014897, 824.8803923431069, 828.3125006244923, 839.5550358310105, 849.3460035772421, 27391.0, 28081.71918280832, 28341.0, 28826.0, 28873.711262887104, 899.298547133224, 944.641036406175, 948.814981078683, 958.7075941837667, 975.3708857786688, 14371.496997605034, 14839.92580037093, 15014.47449339268, 15243.390264389522, 15290.923545382424, 343890.0, 345957.0, 345992.0, 346281.0, 348242.0, 49657.0, 49860.50139498631, 49872.750636247314, 49894.257930655345, 49895.75052124716, 1191.338806962127, 1235.14774805384, 1250.581055346451, 1250.8874201321926, 1253.724180680249, 26148.86925565436, 27110.457790844466, 27309.180724578295, 27586.72413275922, 27976.0, 41941.370879436225, 42458.36312455254, 42495.93760156021, 42522.36216456818, 42591.14817703691, 273668.0, 275609.0, 276087.0, 276132.0, 276472.0, 31966.840165799953, 33084.0, 35111.0, 36438.63561364352, 36464.27071458609, 39714.801425992686, 41749.37375939303, 42578.361324579535, 46236.53763462322, 47766.283505748135, 35363.46954795731, 36072.27855442929, 36964.26071478609, 37844.62155378521, 38269.4259586112, 36194.819025904704, 36280.274394511434, 36316.27367452689, 36587.45118823273, 37089.0, 35683.28633427265, 36461.27077458383, 36641.81679091587, 36942.0, 36975.44536832003, 28385.43229135447, 30072.69927300699, 30168.0, 30180.54729179108, 30590.84704576463, 21198.682019769407, 21407.357779266647, 21504.56990860143, 21559.24542641, 21656.242031528822], "value": 17816.09719054671, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.88345904122116, 353.755908423188, 361.78473808084186, 363.06872871085665, 370.9703223742099, 13628.114172578787, 16345.26446309916, 17140.571485712862, 18444.170012349445, 18720.0, 18194.363197288098, 18242.63514729708, 18325.633487330244, 18370.540736481595, 18394.540136496562, 4456.019675671355, 4664.953350466486, 4686.297055441677, 4742.407199100113, 4753.667243292971, 3682.171511409938, 3816.4466152407886, 4706.599939005188, 4743.098811225873, 4747.0505898820375, 3800.714946379019, 3881.514810648669, 3893.6301051400073, 6090.725917333709, 6148.231471066117, 6164.198654174974, 6165.907511387333, 6167.074938759222, 6174.876502469925, 6191.226096737908, 4741.099191153669, 4963.578095861856, 4992.900141997176, 5067.49325067494, 5075.213341932006, 11919.344436056013, 11949.402529873472, 11998.880011199952, 12116.27302361861, 12167.51329946801, 2156.633372326708, 2177.379446857654, 2268.6143355629583, 2408.650745641872, 2442.3771938155655, 8868.423552469068, 8885.289176865836, 9201.585928633227, 9356.251499879992, 13275.734485310337, 1753.184769082383, 1799.6760583095038, 1819.3086627081625, 1821.4080423862165, 1827.7623908891892, 75602.0, 82069.58965205196, 83465.58267208625, 83556.0, 85900.0, 9931.801363972825, 9985.251106167067, 9992.600295988079, 10291.279610427198, 10359.689209323733, 13693.246871421969, 13695.931520342534, 13704.314784260747, 13739.106958047694, 13830.792538111937, 7172.246914074051, 7302.890456643155, 7307.598082105429, 7481.102267727908, 7572.356349710226, 6949.409300209438, 7023.262557431498, 7165.856682866313, 7182.281771822881, 7199.784006479814, 4036.1725846201457, 4155.625993660525, 4203.747775133441, 4207.957920420818, 4346.543612920661, 1675.5559776659195, 1784.3754685859844, 1785.214505617524, 1809.1316168239055, 1822.8086050964457, 916.5600511754262, 933.0948979489884, 937.3906960475746, 949.3591825517748, 959.0936564946059, 17038.403655871985, 17567.209475573374, 18063.63872722538, 18067.27730890776, 18458.892466452056, 892.723255790707, 898.7213963671282, 916.0564618443015, 928.6935311347239, 938.0478814003792, 9316.254699624014, 9954.402735835873, 9998.80002399948, 10194.0, 10201.355612384357, 509904.0, 516265.0, 525167.0, 526160.0, 539707.0, 94690.5265473682, 95267.0, 95340.52329738445, 99765.53165322589, 100016.0, 883.8497455432592, 915.3043686797948, 934.2152591822901, 936.1247233836249, 937.6624415210518, 14934.925325373304, 15354.0, 15612.21938903073, 15642.0, 15907.761383579023, 39912.80043599763, 40481.547029826106, 41009.794951025055, 41388.379174311805, 42977.78511107424, 50283.74858125686, 52515.0, 54415.72792136014, 55512.444875550726, 56821.0, 5100.056489549445, 5893.469587737124, 7498.287662672037, 7622.047244094489, 7794.805129871798, 11855.755145709922, 12076.275423474619, 12331.383430828244, 12421.503139874303, 12440.315782632042, 10783.784324313312, 10891.0, 10914.672559823219, 10931.0, 11136.498857551267, 10611.0, 10653.30753501051, 10662.25364224528, 10805.729856753644, 11114.333140011626, 10770.13838892871, 10920.50857711389, 10949.890501095206, 10997.890021100007, 11486.597969071043, 7511.5868627223845, 7606.543607383574, 7624.923750762421, 7838.804029899298, 7872.488288261245, 7866.8819967701675, 10532.0, 10532.736071671448, 10701.0, 10935.61725339641], "value": 8344.273171974915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.901457390696, 1330.1752913193818, 1330.7737684593612, 1340.7385559815839, 1342.5905098944818, 6220.377962203777, 6608.504362172832, 6621.073049773024, 6629.071929929808, 6629.171353580802, 5693.146028095777, 5878.265216847894, 6213.068039794033, 6381.521385896053, 6543.280239173699, 2026.7466566679202, 2057.567910738739, 2061.5361543652707, 2061.907214175366, 2063.5460198756323, 1986.692062730279, 2074.502119491319, 2077.2625717870164, 2090.1221486975505, 2095.2352391377112, 1759.3578343904446, 1807.3945228348518, 2002.469345623411, 2056.362527616436, 2060.5054786851124, 2028.0873606876964, 2035.8167764901082, 2037.836973042153, 2042.7344445222175, 2051.138521820839, 1979.9703004454946, 2001.449601359623, 2002.98998505008, 2006.147387360366, 2059.660156074246, 6562.146920900253, 6634.601923884534, 6640.667966601651, 6640.933590664128, 6642.169728783902, 4108.404281379213, 4227.387028880795, 4227.788610569459, 4235.343521754133, 4241.851535196284, 2614.2810727050023, 2746.8901243950218, 2814.3949050954143, 2840.0201930334, 2878.7121287871255, 1049.4647729657854, 1058.317385286486, 1089.3790539392548, 1100.9339439633648, 1123.0, 8419.915800842036, 8446.704365347245, 8464.576771161417, 8467.068622451587, 8468.915310846874, 3537.2218112015316, 3734.8879533614036, 3770.924581508409, 3800.574784455829, 3834.1373191031794, 7436.219196984347, 7451.3293803557945, 7452.21751716062, 7454.2545745426105, 7458.4033277337685, 4213.494380674339, 4234.3860140279485, 4296.2911119664905, 4296.677749168824, 4298.09739954613, 3808.21931504041, 3913.2369188008634, 3920.764754114762, 3922.7057970652313, 3928.0, 3263.282077942849, 3311.089450401159, 3349.363620912019, 3362.6549380247607, 3383.780054296463, 1105.4141305108305, 1116.9162312826409, 1123.5225029362653, 1135.2961164078324, 1139.0545846947014, 762.7864198024562, 777.9883301750478, 783.6512751825497, 797.2983774278714, 801.0587559617543, 4567.954320456819, 4654.604358629555, 4707.599854012447, 4724.503927087606, 4831.251156070814, 510.8978204359143, 511.8490045436605, 520.5783315514445, 521.317074632233, 521.3795583255913, 3140.120766185472, 3187.2828613561765, 3217.8391080445886, 3231.822249776285, 3296.8846090386724, 7175.497715159889, 7303.744368947061, 7431.405487560982, 7458.738944137038, 7687.154413014564, 7643.961780191064, 7658.425618078666, 7659.770206893802, 7693.538387696756, 7695.307422332017, 6132.662703551258, 6134.631922084689, 6276.46650034743, 6324.747010119544, 6332.271788744261, 4893.951060489349, 4979.178435558238, 4989.476105008922, 5025.145725226647, 5058.367704036996, 7142.071530700976, 7145.249748776511, 7148.17795953472, 7152.392046675883, 7153.177384600837, 6958.582485050913, 7138.072050633385, 7157.355837974607, 7223.674934628059, 7313.926860731537, 5802.970985145047, 5830.941690583039, 5856.619319744203, 5908.970455147869, 5936.703164841654, 6208.782692605737, 6210.937890621036, 6234.438900498977, 6237.781677641261, 6256.155419018374, 5756.913646295226, 5828.795992140254, 5834.94165058344, 5894.911576326444, 5967.343592204767, 5682.289713785777, 5738.282714660668, 5742.741576628978, 5769.567282453749, 5807.274090738658, 5139.640225184201, 5181.222816577543, 5216.426193118678, 5273.709945953009, 5340.786368545215, 4211.705180637448, 4220.092680073799, 4287.271163902206, 4336.219480493542, 4410.933835992399, 4327.459067616548, 4350.499692535399, 4370.825166993284, 4389.209942210305, 4454.933176002298], "value": 3634.2176085991628, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.75771761449676, 330.0461665785879, 330.73045467943626, 339.2214866880509, 340.53516949364126, 4656.813727450902, 4724.38582984212, 4819.903601927959, 4827.300041493986, 4836.685615434994, 4520.2767557190855, 4760.309755085511, 4760.381150450446, 4771.546703063212, 4796.424429068508, 3663.5970043295283, 3722.7952462614544, 3727.161388687537, 3754.9436758448646, 3762.6237376262293, 3566.286742651467, 3650.6166852480505, 3684.8710295139676, 3746.4193050077283, 3750.3811871041244, 3709.1283548366114, 3716.24997501848, 3720.962790372089, 3721.9627803722024, 3726.0871086583825, 3701.68535674468, 3711.740178187528, 3723.547816351625, 3730.2725968436166, 3743.513343265358, 3095.7987730797427, 3137.3568418474156, 3190.170555655523, 3244.5944256967878, 3290.901272961815, 4674.415698037746, 4683.1336202802595, 4697.084068606623, 4701.576858082789, 4706.599939005155, 4204.633494114394, 4233.7883105844585, 4233.894152646178, 4237.4491316129015, 4240.618344349024, 4717.9292310615365, 4965.79022335061, 5022.623303252234, 5029.723365214948, 5038.823641172541, 3564.4475106358295, 3575.5888072871694, 3575.94636080459, 3583.7670551414076, 3591.1560783215923, 5291.86770330741, 5332.493413125708, 5333.839984800462, 5341.412444631087, 5341.706206158621, 3636.9818150909077, 3643.180284435981, 3649.94525082124, 3658.63413658632, 3669.981650091786, 3481.2167262365765, 3637.3634613942454, 3648.179159689049, 3654.4152935530187, 3665.780053196817, 4006.4791577094793, 4074.8370065197655, 4093.815778289984, 4109.589041095866, 4122.670186385142, 4876.292937524039, 4966.230234313687, 5046.6719663221775, 5048.829003528518, 5061.873453163626, 1229.0, 1236.0544183699476, 1237.7586370657816, 1239.132607174981, 1249.6563445052502, 806.1253539909147, 835.5863847395578, 843.5149788871423, 859.8667206582865, 867.757028032152, 4313.396124542566, 4315.805788739514, 4321.80186661868, 4326.610605045561, 4334.891627709269, 5035.723035233097, 5066.873328166826, 5070.61970352225, 5073.644844860898, 5101.8724531886255, 4233.026403927114, 4242.257604919127, 4257.957420425818, 4262.424572682648, 4273.230818452646, 2512.6859142607173, 2665.280374298967, 2694.8922043118055, 2698.2984424049496, 2701.7568418842397, 4718.363020992122, 4743.525647435298, 4755.025219829927, 4763.69036012658, 4798.5441383068555, 4921.384826896638, 5190.8183213587345, 5211.983663185642, 5212.801055757197, 5252.2384254282915, 4686.297055441711, 4714.269288260325, 4737.8341758039, 4754.429468463806, 4757.738324392191, 5015.172496538176, 5045.192769156917, 5062.0, 5066.566754090171, 5081.618878584195, 4859.805607775649, 4969.6272779542405, 5010.423801262901, 5018.673786203885, 5029.278334032862, 4840.588549973291, 4874.731889746098, 4883.169861123689, 4884.462709102067, 4899.706017638953, 3618.692411145082, 3638.9636103638622, 3646.690031347365, 3658.414653655402, 3664.5052917855746, 5076.568491678248, 5177.223416487406, 5212.791488340424, 5219.973900130627, 5224.791008359623, 4807.687500312469, 4819.95180048195, 4829.2997515359375, 4829.830955916526, 4842.806287748451, 4804.903901921872, 4824.831130910401, 4826.0830442215865, 4829.13075646387, 4849.684770489906, 4721.244600863845, 4839.733814640229, 4847.587955023862, 4855.927161092516, 4873.269009648439, 4914.385701787277, 4924.409070911371, 4927.334809800631, 4933.447917499395, 4946.163527038129, 4813.772488015534, 4830.0, 4831.468538460696, 4835.178019736723, 4840.273958906192], "value": 3740.822132072343, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.9148264984232, 656.1764984943898, 667.6194569095614, 687.5565260407037, 688.9931100688991, 11751.706207344821, 12405.317707526083, 12423.192492487993, 12425.378731063445, 12427.13010089294, 13240.801387979189, 13287.202767833936, 13350.465981360734, 13395.665108372295, 13395.665108372295, 645.5513418174371, 658.3219284137347, 776.7980325115454, 791.1376599506536, 806.1978331560076, 792.4492477727953, 810.5784991804259, 811.0956283743612, 811.253646645085, 811.7767613906163, 865.2040123086744, 867.2758246863845, 867.7526904832124, 868.0494858130337, 868.1839071273015, 679.5786612300356, 693.0505207865257, 817.7383237364046, 866.1684782608703, 866.3978534918202, 616.3805375597541, 624.0140577886928, 624.0234033737187, 624.4692011789988, 625.1591609285504, 12670.936645316806, 12684.42920068599, 12714.809277860746, 12714.936425317906, 12739.235645861278, 12929.482820687255, 12982.675433114153, 13013.21920684762, 13247.867521324759, 13275.734485310337, 10688.679339619825, 10768.353898766021, 10955.233133680646, 11168.274062185932, 11238.606648767336, 4149.356849688273, 4162.875113746562, 4163.666906647461, 4321.67587430944, 4322.697411181219, 62842.0, 64053.0, 64762.67618661924, 65305.3469465304, 65876.3412365875, 10415.895841041644, 10538.188206172346, 10554.788904221872, 10724.571017159382, 10726.892731072589, 20244.392668219978, 20629.071691773905, 20661.483462913548, 20672.069756860976, 21004.684929726067, 6538.346165383424, 6559.737610495622, 6629.668516574152, 6643.0, 6643.330528349467, 3530.947035794465, 3630.2739452109686, 3643.5809881863925, 3678.8367565849717, 3699.852005919733, 2744.4922689302543, 2794.902178423745, 2809.662840459158, 2822.802403831712, 2832.0, 496.55310220800845, 496.65482489669336, 498.47410981414953, 503.0969409909222, 508.3442359356463, 565.8868226354663, 572.1160806553801, 577.4369989260438, 580.4166812353558, 581.340178896955, 8295.336373090138, 8458.534780587126, 8569.871451928226, 8640.0, 8738.956305218433, 581.7782656421481, 586.6919867069737, 586.762361243697, 596.4602035158189, 617.0867116667357, 3900.941485877714, 4114.238865809834, 4139.0480189555965, 4140.627343539036, 4275.251830929575, 63701.362986369546, 65631.67184164049, 66384.33615663878, 67145.66427167832, 67174.66412667905, 46856.0, 56695.716521417125, 58271.0, 59463.405365945786, 60405.697971509864, 51679.22481162786, 51923.74038129861, 52024.73987630037, 52274.0, 52382.21426678603, 7661.272179142972, 7867.134615192439, 7926.920730792849, 8037.678492860454, 8126.065502467292, 35786.64213357833, 35842.82078589591, 36400.08999775027, 36575.63424365722, 37458.25083498369, 40809.387859181545, 42153.78923105365, 43378.132437351705, 43817.34273985829, 45862.77068614635, 9545.188658964065, 9641.228701703707, 9722.75693107678, 9915.950420247853, 9966.651167209113, 10068.546915388972, 10314.226433017704, 10420.166386688896, 10421.895781042092, 10437.791244175225, 10032.197424206191, 10153.39079655223, 10218.489075546342, 10664.680059598224, 11246.040126715867, 10913.345199288067, 11372.601958931398, 11560.132990025613, 11965.700857478632, 12048.638540843789, 12262.0, 12559.372031398576, 13055.869441305846, 13428.498801293796, 13512.121712088683, 9516.524173791144, 9569.50686065721, 9832.754181145528, 9844.409335439897, 9917.603295868374, 10080.546375412978, 10252.846207306748, 10369.170466362824, 10455.163586912877, 10497.842532362174], "value": 6257.720806464475, "unit": "score"}], "name": "dromaeo_css", "value": 6226.568686631567, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 123.0, 160.0, 129.0, 129.0, 123.0, 125.0, 150.0, 124.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 108.0, 77.0, 77.0, 76.0, 76.0, 92.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 110.0, 85.0, 85.0, 84.0, 100.0, 85.0, 85.0, 102.0], "value": 90.5, "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": [148.0, 149.0, 148.0, 148.0, 149.0, 149.0, 149.0, 150.0, 148.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 60.0, 60.0, 61.0, 62.0, 63.0, 74.0, 63.0, 61.0, 62.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 84.0, 84.0, 85.0, 89.0, 84.0, 84.0, 84.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 134.0, 133.0, 131.0, 143.0, 131.0, 138.0, 180.0, 138.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 174.0, 178.0, 174.0, 175.0, 176.0, 175.0, 173.0, 173.0, 173.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 74.0, 68.0, 67.0, 67.0, 68.0, 70.0, 67.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1540.6000000000001, "alertThreshold": 2.0}]} 18:22:42 INFO - Return code: 0 18:22:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:43 INFO - # TBPL SUCCESS # 18:22:43 INFO - Running post-action listener: _resource_record_post_action 18:22:43 INFO - [mozharness: 2017-01-06 02:22:43.046190Z] Finished run-tests step (success) 18:22:43 INFO - Running post-run listener: _resource_record_post_run 18:22:43 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382521344; Read time: 16; Write time: 350760 18:22:43 INFO - TinderboxPrint: CPU usage
13.1% 18:22:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:22:43 INFO - TinderboxPrint: I/O write bytes / time
382,521,344 / 350,760 18:22:43 INFO - TinderboxPrint: CPU idle
8,337.6 (86.9%) 18:22:43 INFO - TinderboxPrint: CPU user
1,210.9 (12.6%) 18:22:43 INFO - TinderboxPrint: Swap in / out
0 / 0 18:22:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100065280; Read time: 0; Write time: 121636 18:22:43 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 282456064; Read time: 0; Write time: 229124 18:22:43 INFO - Running post-run listener: _upload_blobber_files 18:22:43 INFO - Blob upload gear active. 18:22:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:43 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 18:22:43 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'] 18:22:43 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 18:22:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:44 INFO - (blobuploader) - INFO - Done attempting. 18:22:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:45 INFO - (blobuploader) - INFO - Done attempting. 18:22:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:49 INFO - (blobuploader) - INFO - Done attempting. 18:22:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:22:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:22:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:50 INFO - (blobuploader) - INFO - Done attempting. 18:22:50 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:50 INFO - Return code: 0 18:22:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee7080fa72e5d1dfa1c31f35af63b593482b7ae765e2ba01896490f5977ae43f9aa4416c8bf05534c9f36d9e2bfd6d1de93d3cf95dd24a2a82f927029d27d977", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/599853adddbfe75c85f978dc71f8a8a3eb02f2874f37b794340026f0bad14ba0b50f85aca909892f34ac23da36143810352ee015c9d646b5dcefd7a20c83018b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6aa629574c7c225b435fcddf3582db8875123c3fd57ee2d13e5c6b4ee62415e1d893c1b53efa6b48009209af7342e26a3642a20946d9565218aa2b775dd64fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba57b945433a408323164fc0d506b2984f466e1caa13a647dab30157dc0cf374509562360d6a9bb93a583d2ad86f7fc200dce378bc9b28612f53718792a8f94"} 18:22:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:50 INFO - Contents: 18:22:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee7080fa72e5d1dfa1c31f35af63b593482b7ae765e2ba01896490f5977ae43f9aa4416c8bf05534c9f36d9e2bfd6d1de93d3cf95dd24a2a82f927029d27d977", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/599853adddbfe75c85f978dc71f8a8a3eb02f2874f37b794340026f0bad14ba0b50f85aca909892f34ac23da36143810352ee015c9d646b5dcefd7a20c83018b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6aa629574c7c225b435fcddf3582db8875123c3fd57ee2d13e5c6b4ee62415e1d893c1b53efa6b48009209af7342e26a3642a20946d9565218aa2b775dd64fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba57b945433a408323164fc0d506b2984f466e1caa13a647dab30157dc0cf374509562360d6a9bb93a583d2ad86f7fc200dce378bc9b28612f53718792a8f94"} 18:22:50 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:50 INFO - Copying logs to upload dir... 18:22:50 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.659886 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-05 18:22:50.003556) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 18:22:50.006851) ========= 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=836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee7080fa72e5d1dfa1c31f35af63b593482b7ae765e2ba01896490f5977ae43f9aa4416c8bf05534c9f36d9e2bfd6d1de93d3cf95dd24a2a82f927029d27d977", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/599853adddbfe75c85f978dc71f8a8a3eb02f2874f37b794340026f0bad14ba0b50f85aca909892f34ac23da36143810352ee015c9d646b5dcefd7a20c83018b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6aa629574c7c225b435fcddf3582db8875123c3fd57ee2d13e5c6b4ee62415e1d893c1b53efa6b48009209af7342e26a3642a20946d9565218aa2b775dd64fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba57b945433a408323164fc0d506b2984f466e1caa13a647dab30157dc0cf374509562360d6a9bb93a583d2ad86f7fc200dce378bc9b28612f53718792a8f94"} build_url:https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011623 build_url: 'https://queue.taskcluster.net/v1/task/TCw6MSSUSBWiwJ9qVfHxpw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee7080fa72e5d1dfa1c31f35af63b593482b7ae765e2ba01896490f5977ae43f9aa4416c8bf05534c9f36d9e2bfd6d1de93d3cf95dd24a2a82f927029d27d977", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/599853adddbfe75c85f978dc71f8a8a3eb02f2874f37b794340026f0bad14ba0b50f85aca909892f34ac23da36143810352ee015c9d646b5dcefd7a20c83018b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6aa629574c7c225b435fcddf3582db8875123c3fd57ee2d13e5c6b4ee62415e1d893c1b53efa6b48009209af7342e26a3642a20946d9565218aa2b775dd64fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba57b945433a408323164fc0d506b2984f466e1caa13a647dab30157dc0cf374509562360d6a9bb93a583d2ad86f7fc200dce378bc9b28612f53718792a8f94"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 18:22:50.031958) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:22:50.032255) ========= 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=836cfdc75598d54cb747368e000001a9-1483668120.872040-1774470777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004460 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:22:50.118329) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 18:22:50.118719) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 18:22:50.119009) ========= ========= Total master_lag: 0.18 =========