builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-016 starttime: 1483645272.16 results: success (0) revision: 6db5c37ca63699928d186e500e6cd46d2774f0cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.162728) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.163251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.163584) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.178223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.178537) ========= 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=00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 11:41:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2017-01-05 11:41:12 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136748 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.353863) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.354256) ========= 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=00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014366 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:12.473064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 11:41:12.473460) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 6db5c37ca63699928d186e500e6cd46d2774f0cb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev 6db5c37ca63699928d186e500e6cd46d2774f0cb --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=00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 11:41:12,694 truncating revision to first 12 chars 2017-01-05 11:41:12,694 Setting DEBUG logging. 2017-01-05 11:41:12,694 attempt 1/10 2017-01-05 11:41:12,694 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/6db5c37ca636?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 11:41:13,927 unpacking tar archive at: graphics-6db5c37ca636/testing/mozharness/ program finished with exit code 0 elapsedTime=1.540717 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 11:41:14.129257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:14.129625) ========= script_repo_revision: 6db5c37ca63699928d186e500e6cd46d2774f0cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:14.130166) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:14.130500) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 11:41:14.141687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-05 11:41:14.142024) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:41:14 INFO - MultiFileLogger online at 20170105 11:41:14 in /builds/slave/test 11:41:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 11:41:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:41:14 INFO - {'append_to_log': False, 11:41:14 INFO - 'base_work_dir': '/builds/slave/test', 11:41:14 INFO - 'blob_upload_branch': 'Graphics', 11:41:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:41:14 INFO - 'branch': 'Graphics', 11:41:14 INFO - 'buildbot_json_path': 'buildprops.json', 11:41:14 INFO - 'config_files': ('talos/linux_config.py',), 11:41:14 INFO - 'default_actions': ('clobber', 11:41:14 INFO - 'read-buildbot-config', 11:41:14 INFO - 'download-and-extract', 11:41:14 INFO - 'populate-webroot', 11:41:14 INFO - 'create-virtualenv', 11:41:14 INFO - 'install', 11:41:14 INFO - 'run-tests'), 11:41:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:41:14 INFO - 'download_minidump_stackwalk': True, 11:41:14 INFO - 'download_symbols': 'ondemand', 11:41:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:41:14 INFO - 'tooltool.py': '/tools/tooltool.py', 11:41:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:41:14 INFO - '/tools/misc-python/virtualenv.py')}, 11:41:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:41:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:41:14 INFO - 'installer_path': 'installer.exe', 11:41:14 INFO - 'log_level': 'info', 11:41:14 INFO - 'log_name': 'talos', 11:41:14 INFO - 'log_to_console': True, 11:41:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:41:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:41:14 INFO - 'opt_config_files': (), 11:41:14 INFO - 'pip_index': False, 11:41:14 INFO - 'sps_profile': False, 11:41:14 INFO - 'sps_profile_interval': 0, 11:41:14 INFO - 'suite': 'dromaeojs', 11:41:14 INFO - 'system_bits': '32', 11:41:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:41:14 INFO - 'title': 'talos-linux64-ix-016', 11:41:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:41:14 INFO - 'use_talos_json': True, 11:41:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:41:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:41:14 INFO - 'work_dir': 'build'} 11:41:14 INFO - [mozharness: 2017-01-05 19:41:14.453886Z] Running clobber step. 11:41:14 INFO - Running pre-action listener: _resource_record_pre_action 11:41:14 INFO - Running main action method: clobber 11:41:14 INFO - rmtree: /builds/slave/test/build 11:41:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:41:15 INFO - Running post-action listener: _resource_record_post_action 11:41:15 INFO - [mozharness: 2017-01-05 19:41:15.458716Z] Finished clobber step (success) 11:41:15 INFO - [mozharness: 2017-01-05 19:41:15.458816Z] Running read-buildbot-config step. 11:41:15 INFO - Running pre-action listener: _resource_record_pre_action 11:41:15 INFO - Running main action method: read_buildbot_config 11:41:15 INFO - Using buildbot properties: 11:41:15 INFO - { 11:41:15 INFO - "project": "", 11:41:15 INFO - "product": "firefox", 11:41:15 INFO - "who": "jmuizelaar@mozilla.com", 11:41:15 INFO - "installer_path": "public/build/target.tar.bz2", 11:41:15 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 11:41:15 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 11:41:15 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 11:41:15 INFO - "stage_platform": "linux64", 11:41:15 INFO - "basedir": "/builds/slave/test", 11:41:15 INFO - "buildnumber": 45, 11:41:15 INFO - "platform": "ubuntu64_hw", 11:41:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:41:15 INFO - "slavebuilddir": "test", 11:41:15 INFO - "taskId": "RDz8s6moTUyRavx0SGOd_Q", 11:41:15 INFO - "branch": "graphics", 11:41:15 INFO - "script_repo_revision": "default", 11:41:15 INFO - "revision": "6db5c37ca63699928d186e500e6cd46d2774f0cb", 11:41:15 INFO - "slavename": "talos-linux64-ix-016", 11:41:15 INFO - "repo_path": "projects/graphics" 11:41:15 INFO - } 11:41:15 INFO - Finding installer, test and symbols from parent task. 11:41:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RDz8s6moTUyRavx0SGOd_Q'}, attempt #1 11:41:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RDz8s6moTUyRavx0SGOd_Q'}, attempt #1 11:41:16 INFO - Task dependencies: CKdDg2IjSg--zTlF_0xv_g 11:41:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g'}, attempt #1 11:41:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2 11:41:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/test_packages.json 11:41:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.crashreporter-symbols.zip 11:41:16 INFO - Running post-action listener: _resource_record_post_action 11:41:16 INFO - [mozharness: 2017-01-05 19:41:16.847317Z] Finished read-buildbot-config step (success) 11:41:16 INFO - [mozharness: 2017-01-05 19:41:16.847537Z] Running download-and-extract step. 11:41:16 INFO - Running pre-action listener: _resource_record_pre_action 11:41:16 INFO - Running main action method: download_and_extract 11:41:16 INFO - mkdir: /builds/slave/test/build/tests 11:41: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')]} 11:41:16 INFO - trying https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.test_packages.json 11:41:16 INFO - Downloading https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:41:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:41:17 INFO - Downloaded 896 bytes. 11:41:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:41:17 INFO - Using the following test package requirements: 11:41:17 INFO - {u'common': [u'target.common.tests.zip'], 11:41:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:41:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:41:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:41:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:41:17 INFO - u'mozbase': [u'target.common.tests.zip'], 11:41:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:41:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:41:17 INFO - u'web-platform': [u'target.common.tests.zip', 11:41:17 INFO - u'target.web-platform.tests.zip'], 11:41:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:41:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:41:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.common.tests.zip 11:41:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:41:17 INFO - Fetch https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.common.tests.zip into memory 11:41:18 INFO - Expected file size: 20244966 11:41:18 INFO - Obtained file size: 20244966 11:41:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:41:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.common.tests.zip 11:41:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:41:22 INFO - Fetch https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.common.tests.zip into memory 11:41:23 INFO - Expected file size: 20244966 11:41:23 INFO - Obtained file size: 20244966 11:41:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.talos.tests.zip 11:41:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:41:27 INFO - Fetch https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.talos.tests.zip into memory 11:41:28 INFO - Expected file size: 14403730 11:41:28 INFO - Obtained file size: 14403730 11:41: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')]} 11:41:28 INFO - trying https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2 11:41:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:41:29 INFO - Downloaded 55808482 bytes. 11:41:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2 11:41:29 INFO - mkdir: /builds/slave/test/properties 11:41:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:41:29 INFO - Writing to file /builds/slave/test/properties/build_url 11:41:29 INFO - Contents: 11:41:29 INFO - build_url:https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2 11:41:29 INFO - Running post-action listener: _resource_record_post_action 11:41:29 INFO - Running post-action listener: set_extra_try_arguments 11:41:29 INFO - [mozharness: 2017-01-05 19:41:29.736320Z] Finished download-and-extract step (success) 11:41:29 INFO - [mozharness: 2017-01-05 19:41:29.736417Z] Running populate-webroot step. 11:41:29 INFO - Running pre-action listener: _resource_record_pre_action 11:41:29 INFO - Running main action method: populate_webroot 11:41:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:41:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:41:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:41:29 INFO - '--tppagecycles', 11:41:29 INFO - '7'], 11:41:29 INFO - 'tests': ['tsvgm']}}, 11:41:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:41:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:41:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:41:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:41:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:41:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:41:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['damp', 'tps']}, 11:41:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'tests': ['damp', 'tps']}, 11:41:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['dromaeo_dom']}, 11:41:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:41:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['basic_compositor_video']}, 11:41:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:41:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['a11yr', 11:41:29 INFO - 'ts_paint', 11:41:29 INFO - 'tpaint', 11:41:29 INFO - 'sessionrestore', 11:41:29 INFO - 'sessionrestore_no_auto_restore', 11:41:29 INFO - 'tabpaint']}, 11:41:29 INFO - 'other-e10s': {'tests': ['a11yr', 11:41:29 INFO - 'ts_paint', 11:41:29 INFO - 'tpaint', 11:41:29 INFO - 'sessionrestore', 11:41:29 INFO - 'sessionrestore_no_auto_restore', 11:41:29 INFO - 'tabpaint']}, 11:41:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:41:29 INFO - 'ts_paint', 11:41:29 INFO - 'tpaint', 11:41:29 INFO - 'sessionrestore', 11:41:29 INFO - 'sessionrestore_no_auto_restore', 11:41:29 INFO - 'tabpaint']}, 11:41:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:41:29 INFO - 'ts_paint', 11:41:29 INFO - 'tpaint', 11:41:29 INFO - 'sessionrestore', 11:41:29 INFO - 'sessionrestore_no_auto_restore', 11:41:29 INFO - 'tabpaint']}, 11:41:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['a11yr', 11:41:29 INFO - 'ts_paint', 11:41:29 INFO - 'tpaint', 11:41:29 INFO - 'sessionrestore', 11:41:29 INFO - 'sessionrestore_no_auto_restore', 11:41:29 INFO - 'tabpaint']}, 11:41:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['a11yr', 11:41:29 INFO - 'ts_paint', 11:41:29 INFO - 'tpaint', 11:41:29 INFO - 'sessionrestore', 11:41:29 INFO - 'sessionrestore_no_auto_restore', 11:41:29 INFO - 'tabpaint']}, 11:41:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['tsvgx', 11:41:29 INFO - 'tsvgr_opacity', 11:41:29 INFO - 'tart', 11:41:29 INFO - 'tscrollx', 11:41:29 INFO - 'cart', 11:41:29 INFO - 'tsvg_static']}, 11:41:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:41:29 INFO - 'tsvgr_opacity', 11:41:29 INFO - 'tart', 11:41:29 INFO - 'tscrollx', 11:41:29 INFO - 'cart', 11:41:29 INFO - 'tsvg_static']}, 11:41:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'talos_options': ['--disable-e10s'], 11:41:29 INFO - 'tests': ['tp5o']}, 11:41:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:41:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'talos_options': ['--disable-e10s', 11:41:29 INFO - '--xperf_path', 11:41:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:41:29 INFO - 'tests': ['tp5n']}, 11:41:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:41:29 INFO - 'talos_options': ['--xperf_path', 11:41:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:41:29 INFO - 'tests': ['tp5n']}}, 11:41:29 INFO - 'talos.zip': {'path': '', 11:41:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:41:29 INFO - Running post-action listener: _resource_record_post_action 11:41:29 INFO - [mozharness: 2017-01-05 19:41:29.749759Z] Finished populate-webroot step (success) 11:41:29 INFO - [mozharness: 2017-01-05 19:41:29.749854Z] Running create-virtualenv step. 11:41:29 INFO - Running pre-action listener: _resource_record_pre_action 11:41:29 INFO - Running main action method: create_virtualenv 11:41:29 INFO - Creating virtualenv /builds/slave/test/build/venv 11:41:29 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 11:41:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:41:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:41:29 INFO - Using real prefix '/usr' 11:41:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:41:30 INFO - Installing distribute.............................................................................................................................................................................................done. 11:41:32 INFO - Installing pip.................done. 11:41:32 INFO - Return code: 0 11:41:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:41:32 INFO - Reading from file tmpfile_stdout 11:41:32 INFO - Output received: 11:41:32 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) 11:41:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:32 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:32 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 11:41:32 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 11:41:32 INFO - Using env: {'DISPLAY': ':0', 11:41:32 INFO - 'HOME': '/home/cltbld', 11:41:32 INFO - 'LANG': 'en_US.UTF-8', 11:41:32 INFO - 'LANGUAGE': 'en_US:en', 11:41:32 INFO - 'LOGNAME': 'cltbld', 11:41:32 INFO - 'MAIL': '/var/mail/cltbld', 11:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:32 INFO - 'MOZ_NO_REMOTE': '1', 11:41:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:32 INFO - 'NO_EM_RESTART': '1', 11:41:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:32 INFO - 'PWD': '/builds/slave/test', 11:41:32 INFO - 'SHELL': '/bin/bash', 11:41:32 INFO - 'SHLVL': '1', 11:41:32 INFO - 'TERM': 'linux', 11:41:32 INFO - 'TMOUT': '86400', 11:41:32 INFO - 'USER': 'cltbld', 11:41:32 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:32 INFO - '_': '/tools/buildbot/bin/python'} 11:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:32 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 11:41:32 INFO - Cleaning up... 11:41:32 INFO - Return code: 0 11:41:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:32 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:32 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 11:41:32 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 11:41:32 INFO - Using env: {'DISPLAY': ':0', 11:41:32 INFO - 'HOME': '/home/cltbld', 11:41:32 INFO - 'LANG': 'en_US.UTF-8', 11:41:32 INFO - 'LANGUAGE': 'en_US:en', 11:41:32 INFO - 'LOGNAME': 'cltbld', 11:41:32 INFO - 'MAIL': '/var/mail/cltbld', 11:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:32 INFO - 'MOZ_NO_REMOTE': '1', 11:41:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:32 INFO - 'NO_EM_RESTART': '1', 11:41:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:32 INFO - 'PWD': '/builds/slave/test', 11:41:32 INFO - 'SHELL': '/bin/bash', 11:41:32 INFO - 'SHLVL': '1', 11:41:32 INFO - 'TERM': 'linux', 11:41:32 INFO - 'TMOUT': '86400', 11:41:32 INFO - 'USER': 'cltbld', 11:41:32 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:32 INFO - '_': '/tools/buildbot/bin/python'} 11:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:32 INFO - Downloading/unpacking psutil>=3.1.1 11:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:41:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:41:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:41:35 INFO - Installing collected packages: psutil 11:41:35 INFO - Running setup.py install for psutil 11:41:35 INFO - building 'psutil._psutil_linux' extension 11:41:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:41:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:41:35 INFO - building 'psutil._psutil_posix' extension 11:41:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:41:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:41:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:41:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:41:35 INFO - Successfully installed psutil 11:41:35 INFO - Cleaning up... 11:41:35 INFO - Return code: 0 11:41:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:41:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:41:35 INFO - Using env: {'DISPLAY': ':0', 11:41:35 INFO - 'HOME': '/home/cltbld', 11:41:35 INFO - 'LANG': 'en_US.UTF-8', 11:41:35 INFO - 'LANGUAGE': 'en_US:en', 11:41:35 INFO - 'LOGNAME': 'cltbld', 11:41:35 INFO - 'MAIL': '/var/mail/cltbld', 11:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:35 INFO - 'MOZ_NO_REMOTE': '1', 11:41:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:35 INFO - 'NO_EM_RESTART': '1', 11:41:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:35 INFO - 'PWD': '/builds/slave/test', 11:41:35 INFO - 'SHELL': '/bin/bash', 11:41:35 INFO - 'SHLVL': '1', 11:41:35 INFO - 'TERM': 'linux', 11:41:35 INFO - 'TMOUT': '86400', 11:41:35 INFO - 'USER': 'cltbld', 11:41:35 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:35 INFO - '_': '/tools/buildbot/bin/python'} 11:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:41:35 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 11:41:35 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 11:41:35 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 11:41:35 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 11:41:35 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 11:41:35 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 11:41:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:41:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:41:37 INFO - Installing collected packages: mozsystemmonitor 11:41:37 INFO - Running setup.py install for mozsystemmonitor 11:41:37 INFO - Successfully installed mozsystemmonitor 11:41:37 INFO - Cleaning up... 11:41:38 INFO - Return code: 0 11:41:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:41:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:41:38 INFO - Using env: {'DISPLAY': ':0', 11:41:38 INFO - 'HOME': '/home/cltbld', 11:41:38 INFO - 'LANG': 'en_US.UTF-8', 11:41:38 INFO - 'LANGUAGE': 'en_US:en', 11:41:38 INFO - 'LOGNAME': 'cltbld', 11:41:38 INFO - 'MAIL': '/var/mail/cltbld', 11:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:38 INFO - 'MOZ_NO_REMOTE': '1', 11:41:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:38 INFO - 'NO_EM_RESTART': '1', 11:41:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:38 INFO - 'PWD': '/builds/slave/test', 11:41:38 INFO - 'SHELL': '/bin/bash', 11:41:38 INFO - 'SHLVL': '1', 11:41:38 INFO - 'TERM': 'linux', 11:41:38 INFO - 'TMOUT': '86400', 11:41:38 INFO - 'USER': 'cltbld', 11:41:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:38 INFO - '_': '/tools/buildbot/bin/python'} 11:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:38 INFO - Downloading/unpacking jsonschema==2.5.1 11:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:41:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:40 INFO - Downloading functools32-3.2.3-2.tar.gz 11:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:41:40 INFO - warning: no files found matching '*.txt' 11:41:40 INFO - no previously-included directories found matching 'build' 11:41:40 INFO - no previously-included directories found matching 'dist' 11:41:40 INFO - no previously-included directories found matching '.git*' 11:41:40 INFO - Installing collected packages: jsonschema, functools32 11:41:40 INFO - Running setup.py install for functools32 11:41:40 INFO - warning: no files found matching '*.txt' 11:41:40 INFO - no previously-included directories found matching 'build' 11:41:40 INFO - no previously-included directories found matching 'dist' 11:41:40 INFO - no previously-included directories found matching '.git*' 11:41:40 INFO - Successfully installed jsonschema functools32 11:41:40 INFO - Cleaning up... 11:41:40 INFO - Return code: 0 11:41:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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', '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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:41: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 functools32==3.2.3-2 11:41:40 INFO - Using env: {'DISPLAY': ':0', 11:41:40 INFO - 'HOME': '/home/cltbld', 11:41:40 INFO - 'LANG': 'en_US.UTF-8', 11:41:40 INFO - 'LANGUAGE': 'en_US:en', 11:41:40 INFO - 'LOGNAME': 'cltbld', 11:41:40 INFO - 'MAIL': '/var/mail/cltbld', 11:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:40 INFO - 'MOZ_NO_REMOTE': '1', 11:41:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:40 INFO - 'NO_EM_RESTART': '1', 11:41:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:40 INFO - 'PWD': '/builds/slave/test', 11:41:40 INFO - 'SHELL': '/bin/bash', 11:41:40 INFO - 'SHLVL': '1', 11:41:40 INFO - 'TERM': 'linux', 11:41:40 INFO - 'TMOUT': '86400', 11:41:40 INFO - 'USER': 'cltbld', 11:41:40 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:40 INFO - '_': '/tools/buildbot/bin/python'} 11:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:41:40 INFO - Cleaning up... 11:41:40 INFO - Return code: 0 11:41:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:41:40 INFO - Using env: {'DISPLAY': ':0', 11:41:40 INFO - 'HOME': '/home/cltbld', 11:41:40 INFO - 'LANG': 'en_US.UTF-8', 11:41:40 INFO - 'LANGUAGE': 'en_US:en', 11:41:40 INFO - 'LOGNAME': 'cltbld', 11:41:40 INFO - 'MAIL': '/var/mail/cltbld', 11:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:40 INFO - 'MOZ_NO_REMOTE': '1', 11:41:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:40 INFO - 'NO_EM_RESTART': '1', 11:41:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:40 INFO - 'PWD': '/builds/slave/test', 11:41:40 INFO - 'SHELL': '/bin/bash', 11:41:40 INFO - 'SHLVL': '1', 11:41:40 INFO - 'TERM': 'linux', 11:41:40 INFO - 'TMOUT': '86400', 11:41:40 INFO - 'USER': 'cltbld', 11:41:40 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:40 INFO - '_': '/tools/buildbot/bin/python'} 11:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:41 INFO - Downloading/unpacking blobuploader==1.2.4 11:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:43 INFO - Downloading blobuploader-1.2.4.tar.gz 11:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:41:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:41:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:43 INFO - Downloading docopt-0.6.1.tar.gz 11:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:41:43 INFO - Installing collected packages: blobuploader, requests, docopt 11:41:43 INFO - Running setup.py install for blobuploader 11:41:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:41:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:41:44 INFO - Running setup.py install for requests 11:41:44 INFO - Running setup.py install for docopt 11:41:44 INFO - Successfully installed blobuploader requests docopt 11:41:44 INFO - Cleaning up... 11:41:44 INFO - Return code: 0 11:41:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41:44 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')]} 11:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:44 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')]} 11:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:44 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:44 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 11:41:44 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 11:41:44 INFO - Using env: {'DISPLAY': ':0', 11:41:44 INFO - 'HOME': '/home/cltbld', 11:41:44 INFO - 'LANG': 'en_US.UTF-8', 11:41:44 INFO - 'LANGUAGE': 'en_US:en', 11:41:44 INFO - 'LOGNAME': 'cltbld', 11:41:44 INFO - 'MAIL': '/var/mail/cltbld', 11:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:44 INFO - 'MOZ_NO_REMOTE': '1', 11:41:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:44 INFO - 'NO_EM_RESTART': '1', 11:41:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:44 INFO - 'PWD': '/builds/slave/test', 11:41:44 INFO - 'SHELL': '/bin/bash', 11:41:44 INFO - 'SHLVL': '1', 11:41:44 INFO - 'TERM': 'linux', 11:41:44 INFO - 'TMOUT': '86400', 11:41:44 INFO - 'USER': 'cltbld', 11:41:44 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:44 INFO - '_': '/tools/buildbot/bin/python'} 11:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:41:44 INFO - Running setup.py (path:/tmp/pip-JUe_fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:41:44 INFO - Running setup.py (path:/tmp/pip-xgwTYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:41:44 INFO - Running setup.py (path:/tmp/pip-RxeBOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:41:45 INFO - Running setup.py (path:/tmp/pip-slXr46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:41:45 INFO - Running setup.py (path:/tmp/pip-vAmHtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:41:45 INFO - Running setup.py (path:/tmp/pip-Da5s7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:41:45 INFO - Running setup.py (path:/tmp/pip-xnpd3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:41:45 INFO - Running setup.py (path:/tmp/pip-2mNKMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:41:45 INFO - Running setup.py (path:/tmp/pip-R4n9os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:41:45 INFO - Running setup.py (path:/tmp/pip-7D5oXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:41:45 INFO - Running setup.py (path:/tmp/pip-XBMQ3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:41:45 INFO - Running setup.py (path:/tmp/pip-L9U4kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:41:45 INFO - Running setup.py (path:/tmp/pip-Ca4xMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:41:45 INFO - Running setup.py (path:/tmp/pip-5Kghpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:45 INFO - Running setup.py (path:/tmp/pip-bCwSXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:41:45 INFO - Running setup.py (path:/tmp/pip-seBNEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:41:45 INFO - Running setup.py (path:/tmp/pip-Rtd5Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:41:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:41:46 INFO - Running setup.py install for manifestparser 11:41:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Running setup.py install for mozcrash 11:41:46 INFO - Running setup.py install for mozdebug 11:41:46 INFO - Running setup.py install for mozdevice 11:41:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Running setup.py install for mozfile 11:41:46 INFO - Running setup.py install for mozhttpd 11:41:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Running setup.py install for mozinfo 11:41:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Running setup.py install for mozInstall 11:41:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:41:46 INFO - Running setup.py install for mozleak 11:41:46 INFO - Running setup.py install for mozlog 11:41:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Running setup.py install for moznetwork 11:41:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Running setup.py install for mozprocess 11:41:47 INFO - Running setup.py install for mozprofile 11:41:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Running setup.py install for mozrunner 11:41:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Running setup.py install for mozscreenshot 11:41:47 INFO - Running setup.py install for moztest 11:41:47 INFO - Running setup.py install for mozversion 11:41:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:41:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:41:47 INFO - Cleaning up... 11:41:47 INFO - Return code: 0 11:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:47 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:47 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 11:41:47 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 11:41:47 INFO - Using env: {'DISPLAY': ':0', 11:41:47 INFO - 'HOME': '/home/cltbld', 11:41:47 INFO - 'LANG': 'en_US.UTF-8', 11:41:47 INFO - 'LANGUAGE': 'en_US:en', 11:41:47 INFO - 'LOGNAME': 'cltbld', 11:41:47 INFO - 'MAIL': '/var/mail/cltbld', 11:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:47 INFO - 'MOZ_NO_REMOTE': '1', 11:41:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:47 INFO - 'NO_EM_RESTART': '1', 11:41:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:47 INFO - 'PWD': '/builds/slave/test', 11:41:47 INFO - 'SHELL': '/bin/bash', 11:41:47 INFO - 'SHLVL': '1', 11:41:47 INFO - 'TERM': 'linux', 11:41:47 INFO - 'TMOUT': '86400', 11:41:47 INFO - 'USER': 'cltbld', 11:41:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:47 INFO - '_': '/tools/buildbot/bin/python'} 11:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:41:48 INFO - Running setup.py (path:/tmp/pip-uolZdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:41:48 INFO - Running setup.py (path:/tmp/pip-2oMzhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:41:48 INFO - Running setup.py (path:/tmp/pip-dO1OIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:41:48 INFO - Running setup.py (path:/tmp/pip-sxL1DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:41:48 INFO - Running setup.py (path:/tmp/pip-XpPJGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:41:48 INFO - Running setup.py (path:/tmp/pip-CPTvW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:41:48 INFO - Running setup.py (path:/tmp/pip-hnkDRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:41:48 INFO - Running setup.py (path:/tmp/pip-Ic8Sa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:41:48 INFO - Running setup.py (path:/tmp/pip-oLOO9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:41:48 INFO - Running setup.py (path:/tmp/pip-9Bc0Vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:41:48 INFO - Running setup.py (path:/tmp/pip-HbgnXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:41:48 INFO - Running setup.py (path:/tmp/pip-mjhsmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:41:48 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)) 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:41:48 INFO - Running setup.py (path:/tmp/pip-dNRsto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:41:49 INFO - Running setup.py (path:/tmp/pip-GC6yBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:49 INFO - Running setup.py (path:/tmp/pip-IGNdb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:41:49 INFO - Running setup.py (path:/tmp/pip-BHdsAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:41:49 INFO - Running setup.py (path:/tmp/pip-ZzrNm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:41:49 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)) 11:41:49 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)) 11:41:49 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)) 11:41:49 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)) 11:41:49 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)) 11:41:49 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)) 11:41:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:41:49 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 11:41:49 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 11:41:49 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 11:41:49 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 11:41:49 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 11:41:49 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 11:41:51 INFO - Downloading blessings-1.6.tar.gz 11:41:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:41:51 INFO - Installing collected packages: blessings 11:41:51 INFO - Running setup.py install for blessings 11:41:51 INFO - Successfully installed blessings 11:41:51 INFO - Cleaning up... 11:41:51 INFO - Return code: 0 11:41:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:41:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:41:51 INFO - Reading from file tmpfile_stdout 11:41:51 INFO - Current package versions: 11:41:51 INFO - argparse == 1.2.1 11:41:51 INFO - blessings == 1.6 11:41:51 INFO - blobuploader == 1.2.4 11:41:51 INFO - docopt == 0.6.1 11:41:51 INFO - functools32 == 3.2.3-2 11:41:51 INFO - jsonschema == 2.5.1 11:41:51 INFO - manifestparser == 1.1 11:41:51 INFO - mozInstall == 1.12 11:41:51 INFO - mozcrash == 1.0 11:41:51 INFO - mozdebug == 0.1 11:41:51 INFO - mozdevice == 0.49 11:41:51 INFO - mozfile == 1.2 11:41:51 INFO - mozhttpd == 0.7 11:41:51 INFO - mozinfo == 0.9 11:41:51 INFO - mozleak == 0.1 11:41:51 INFO - mozlog == 3.4 11:41:51 INFO - moznetwork == 0.27 11:41:51 INFO - mozprocess == 0.23 11:41:51 INFO - mozprofile == 0.28 11:41:51 INFO - mozrunner == 6.13 11:41:51 INFO - mozscreenshot == 0.1 11:41:51 INFO - mozsystemmonitor == 0.3 11:41:51 INFO - moztest == 0.8 11:41:51 INFO - mozversion == 1.4 11:41:51 INFO - psutil == 3.1.1 11:41:51 INFO - requests == 1.2.3 11:41:51 INFO - wsgiref == 0.1.2 11:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:41:51 INFO - Using env: {'DISPLAY': ':0', 11:41:51 INFO - 'HOME': '/home/cltbld', 11:41:51 INFO - 'LANG': 'en_US.UTF-8', 11:41:51 INFO - 'LANGUAGE': 'en_US:en', 11:41:51 INFO - 'LOGNAME': 'cltbld', 11:41:51 INFO - 'MAIL': '/var/mail/cltbld', 11:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:51 INFO - 'MOZ_NO_REMOTE': '1', 11:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:51 INFO - 'NO_EM_RESTART': '1', 11:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:51 INFO - 'PWD': '/builds/slave/test', 11:41:51 INFO - 'SHELL': '/bin/bash', 11:41:51 INFO - 'SHLVL': '1', 11:41:51 INFO - 'TERM': 'linux', 11:41:51 INFO - 'TMOUT': '86400', 11:41:51 INFO - 'USER': 'cltbld', 11:41:51 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:51 INFO - '_': '/tools/buildbot/bin/python'} 11:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:41:52 INFO - Cleaning up... 11:41:52 INFO - Return code: 0 11:41:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, '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': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', '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 11:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:41:52 INFO - Using env: {'DISPLAY': ':0', 11:41:52 INFO - 'HOME': '/home/cltbld', 11:41:52 INFO - 'LANG': 'en_US.UTF-8', 11:41:52 INFO - 'LANGUAGE': 'en_US:en', 11:41:52 INFO - 'LOGNAME': 'cltbld', 11:41:52 INFO - 'MAIL': '/var/mail/cltbld', 11:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:52 INFO - 'MOZ_NO_REMOTE': '1', 11:41:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:41:52 INFO - 'NO_EM_RESTART': '1', 11:41:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:52 INFO - 'PWD': '/builds/slave/test', 11:41:52 INFO - 'SHELL': '/bin/bash', 11:41:52 INFO - 'SHLVL': '1', 11:41:52 INFO - 'TERM': 'linux', 11:41:52 INFO - 'TMOUT': '86400', 11:41:52 INFO - 'USER': 'cltbld', 11:41:52 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:52 INFO - '_': '/tools/buildbot/bin/python'} 11:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:41:52 INFO - Cleaning up... 11:41:52 INFO - Return code: 0 11:41:52 INFO - Running post-action listener: _resource_record_post_action 11:41:52 INFO - Running post-action listener: _start_resource_monitoring 11:41:52 INFO - Starting resource monitoring. 11:41:52 INFO - [mozharness: 2017-01-05 19:41:52.289746Z] Finished create-virtualenv step (success) 11:41:52 INFO - [mozharness: 2017-01-05 19:41:52.290207Z] Running install step. 11:41:52 INFO - Running pre-action listener: _resource_record_pre_action 11:41:52 INFO - Running main action method: install 11:41:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:41:52 INFO - Reading from file tmpfile_stdout 11:41:52 INFO - Detecting whether we're running mozinstall >=1.0... 11:41:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:41:52 INFO - Reading from file tmpfile_stdout 11:41:52 INFO - Output received: 11:41:52 INFO - Usage: mozinstall [options] installer 11:41:52 INFO - Options: 11:41:52 INFO - -h, --help show this help message and exit 11:41:52 INFO - -d DEST, --destination=DEST 11:41:52 INFO - Directory to install application into. [default: 11:41:52 INFO - "/builds/slave/test"] 11:41:52 INFO - --app=APP Application being installed. [default: firefox] 11:41:52 INFO - mkdir: /builds/slave/test/build/application 11:41:52 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'] 11:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:42:08 INFO - Reading from file tmpfile_stdout 11:42:08 INFO - Output received: 11:42:08 INFO - /builds/slave/test/build/application/firefox/firefox 11:42:08 INFO - Running post-action listener: _resource_record_post_action 11:42:08 INFO - [mozharness: 2017-01-05 19:42:08.722907Z] Finished install step (success) 11:42:08 INFO - [mozharness: 2017-01-05 19:42:08.723185Z] Running run-tests step. 11:42:08 INFO - Running pre-action listener: _resource_record_pre_action 11:42:08 INFO - Running main action method: run_tests 11:42:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:42:08 INFO - Python 2.7.3 11:42:08 INFO - Return code: 0 11:42:08 INFO - grabbing minidump binary from tooltool 11:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:08 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 0x1f743f0>, '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 0x1f74cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f751a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:42:08 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 11:42:08 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 11:42:08 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 11:42:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:42:08 INFO - Return code: 0 11:42:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:42:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:42:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:42:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:42:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:42:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 11:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 11:42:08 INFO - Using env: {'DISPLAY': ':0', 11:42:08 INFO - 'HOME': '/home/cltbld', 11:42:08 INFO - 'LANG': 'en_US.UTF-8', 11:42:08 INFO - 'LANGUAGE': 'en_US:en', 11:42:08 INFO - 'LOGNAME': 'cltbld', 11:42:08 INFO - 'MAIL': '/var/mail/cltbld', 11:42:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:42:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:08 INFO - 'MOZ_NO_REMOTE': '1', 11:42:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:42:08 INFO - 'NO_EM_RESTART': '1', 11:42:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:08 INFO - 'PWD': '/builds/slave/test', 11:42:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:42:08 INFO - 'SHELL': '/bin/bash', 11:42:08 INFO - 'SHLVL': '1', 11:42:08 INFO - 'TERM': 'linux', 11:42:08 INFO - 'TMOUT': '86400', 11:42:08 INFO - 'USER': 'cltbld', 11:42:08 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669', 11:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:08 INFO - '_': '/tools/buildbot/bin/python'} 11:42:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 11:42:08 INFO - mozversion application_buildid: 20170105190241 11:42:08 INFO - mozversion application_changeset: 6db5c37ca63699928d186e500e6cd46d2774f0cb 11:42:08 INFO - mozversion application_display_name: Nightly 11:42:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:42:08 INFO - mozversion application_name: Firefox 11:42:08 INFO - mozversion application_remotingname: firefox 11:42:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 11:42:08 INFO - mozversion application_vendor: Mozilla 11:42:08 INFO - mozversion application_version: 53.0a1 11:42:08 INFO - mozversion platform_buildid: 20170105190241 11:42:08 INFO - mozversion platform_changeset: 6db5c37ca63699928d186e500e6cd46d2774f0cb 11:42:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 11:42:08 INFO - mozversion platform_version: 53.0a1 11:42:08 INFO - using testdate: 1483645328 11:42:08 INFO - actual date: 1483645328 11:42:08 INFO - starting webserver on 'localhost:58081' 11:42:08 INFO - SUITE-START | Running 2 tests 11:42:08 INFO - TEST-START | dromaeo_css 11:42:08 INFO - operating with platform_type : linux_ 11:42:08 INFO - Initialising browser for dromaeo_css test... 11:42:09 INFO - TEST-INFO | started process 960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxj4_m7/profile http://localhost:58081/getInfo.html) 11:42:09 INFO - PROCESS | 960 | 1483645329493 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:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 11:42:13 INFO - PROCESS | 960 | __metrics Screen width/height:1600/1200 11:42:13 INFO - PROCESS | 960 | colorDepth:24 11:42:13 INFO - PROCESS | 960 | Browser inner width/height: 1024/697 11:42:13 INFO - PROCESS | 960 | __metrics 11:42:13 INFO - PROCESS | 960 | 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" 11:42:13 INFO - PROCESS | 960 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:42:14 INFO - PROCESS | 960 | console.error: PushService: 11:42:14 INFO - PROCESS | 960 | stateChangeProcessEnqueue: Error transitioning state 11:42:14 INFO - PROCESS | 960 | UnknownError 11:42:15 INFO - TEST-INFO | 960: exit 0 11:42:15 INFO - Browser initialized. 11:42:15 INFO - Running cycle 1/1 for dromaeo_css test... 11:42:15 INFO - TEST-INFO | started process 1048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxj4_m7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:27 INFO - PROCESS | 1048 | RSS: Main: 150966272 11:42:27 INFO - PROCESS | 1048 | 11:43:17 INFO - PROCESS | 1048 | 1483645397913 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:45:32 INFO - PROCESS | 1048 | Cycle 1(1): loaded http://localhost:58081/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58081/tests/dromaeo/cssquery-ext.html) 11:45:33 INFO - PROCESS | 1048 | RSS: Main: 253865984 11:45:33 INFO - PROCESS | 1048 | 11:47:16 INFO - PROCESS | 1048 | ************************* 11:47:16 INFO - PROCESS | 1048 | A coding exception was thrown and uncaught in a Task. 11:47:16 INFO - PROCESS | 1048 | 11:47:16 INFO - PROCESS | 1048 | Full message: ReferenceError: fetch is not defined 11:47:16 INFO - PROCESS | 1048 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:47:16 INFO - PROCESS | 1048 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:47:16 INFO - PROCESS | 1048 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:47:16 INFO - PROCESS | 1048 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:47:16 INFO - PROCESS | 1048 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:47:16 INFO - PROCESS | 1048 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:47:16 INFO - PROCESS | 1048 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:47:16 INFO - PROCESS | 1048 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:47:16 INFO - PROCESS | 1048 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:47:16 INFO - PROCESS | 1048 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:47:16 INFO - PROCESS | 1048 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:47:16 INFO - PROCESS | 1048 | 11:47:16 INFO - PROCESS | 1048 | ************************* 11:48:36 INFO - PROCESS | 1048 | Cycle 1(1): loaded http://localhost:58081/tests/dromaeo/cssquery-ext.html (next: http://localhost:58081/tests/dromaeo/cssquery-jquery.html) 11:48:36 INFO - PROCESS | 1048 | RSS: Main: 237359104 11:48:36 INFO - PROCESS | 1048 | 11:51:39 INFO - PROCESS | 1048 | Cycle 1(1): loaded http://localhost:58081/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58081/tests/dromaeo/cssquery-mootools.html) 11:51:39 INFO - PROCESS | 1048 | RSS: Main: 183472128 11:51:39 INFO - PROCESS | 1048 | 11:54:41 INFO - PROCESS | 1048 | Cycle 1(1): loaded http://localhost:58081/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58081/tests/dromaeo/cssquery-prototype.html) 11:54:42 INFO - PROCESS | 1048 | RSS: Main: 184168448 11:54:42 INFO - PROCESS | 1048 | 11:57:45 INFO - PROCESS | 1048 | Cycle 1(1): loaded http://localhost:58081/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58081/tests/dromaeo/cssquery-yui.html) 11:57:46 INFO - PROCESS | 1048 | RSS: Main: 183267328 11:57:46 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | Cycle 1(1): loaded http://localhost:58081/tests/dromaeo/cssquery-yui.html (next: http://localhost:58081/tests/dromaeo/cssquery-dojo.html) 12:00:49 INFO - PROCESS | 1048 | RSS: Main: 271249408 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | __start_tp_report 12:00:49 INFO - PROCESS | 1048 | _x_x_mozilla_page_load 12:00:49 INFO - PROCESS | 1048 | _x_x_mozilla_page_load_details 12:00:49 INFO - PROCESS | 1048 | |i|pagename|runs| 12:00:49 INFO - PROCESS | 1048 | |0;dojo.html;2558.7185409604945;2568.409265868849;2589.4432696970134;2592.4426248356613;2595.7793587545057;4632.768361581916;4686.062787442516;4727.52724727527;4744.0037592105655;4750.477447480779;4727.881802954919;4757.809687612491;4773.284007398892;4791.329213910055;4880.341153944217;4395.208862404766;4411.073674528337;4439.77801109944;4461.776911154446;4467.798949047301;3958.1163313414795;4402.25161722508;4405;4436.711613745112;4453.688241823073;4492.415985921825;4500.39244701966;4503.684742068057;4517.299818528117;4521.954780452186;4320.265554855682;4336.783160841946;4351.303791393362;4402.8679139625865;4426.535213802569;4331.740095594275;4339.06710057336;4373.540778218305;4400.647948164139;4407.867763967086;4723.149833030053;4745.6440766942615;4762.523747625244;4763.071201115784;4790.688605240648;5071.1632580624155;5084.466131056222;5102.566281866046;5106.846794596168;5155.072087024334;4976.776045077981;5004.099262132815;5061.063703214917;5092.388913330424;5103.081445339837;4804.927926081112;4818.6626936114135;4819.879503012383;4844.152273352184;4860.295257187688;5232.058229518723;5232.947670523246;5253.921191182136;5254.97372513135;5269.578433725293;4899.485554016848;4928.704277743347;4966.279889416014;4996.975015124974;5016.122178618727;5099.286099946011;5101.081805275012;5104.795808167706;5137.6660517066275;5156.097682905476;4952.331435256267;4966.354373931438;4967.552920237196;4980.90038199234;5056.924146137737;4993.625478089158;4994.775235114428;5037.4458809530925;5088.796448142033;5094.719790411563;4232.513260974965;4251.532331443539;4302.548232435612;4316.676249281317;4360.106178233455;3733.029412352808;3765.1151979284846;3789.488419063391;3803.7527560708463;3805;4389;4390.084088675312;4397.934030989538;4419.513853476115;4451.554844515587;4992.525710057539;5034.949650503521;5138.820141295037;5141.948580514147;5177.404598471224;4380.189664912002;4402.185595664812;4408.493023302297;4408.537103604075;4462.933056004163;4620.330052142488;4620.514845941195;4623.745693986796;4628.282616194495;4673.602743766818;4612.953870461387;4800.783964721526;4803.651608742059;4804.495527969513;4830.396200474941;4985.252212168204;5086.415062267886;5130.179171332718;5140.742962851918;5201.141811601044;4781.378420805273;4787.257975013878;4790.664653474363;4791.281307803857;4834.3231947528075;5080.618953578422;5087.2623469595965;5088.898222035613;5088.974555127349;5129.563987060992;4929.112759703145;4977.402711674622;5089.541941225308;5142.485751424828;5147.510986456281;5027.095122877917;5029.974850125726;5034.823781167642;5036.949630503648;5061.8734531637;4813.711177329372;4858.562729354375;5021.84934451967;5032.874178145576;5051.646384753032;4836.25038119078;4880.023995201045;4914.852554423373;4917.188663870565;4993.151164302005;5039.823606173766;5045.7981680732355;5047.192449208109;5052.9494705052475;5092.541671249605;4856.5871900887405;4926.704397736147;4932.605391568594;4934.358533390637;4970.8757281068265 12:00:49 INFO - PROCESS | 1048 | |1;ext.html;537.3578573604542;537.5135502370355;548.4077196627643;569.6553585081026;573.3377948469517;31672.366552668962;42357.78821105898;44519.999999999956;44572.554274457245;44741.32888006674;41816.16367672645;42333.57666423335;42345.576544234624;42345.7882710586;42428.57571424285;7635.350995165418;7724.61376931154;7892.921070789304;7894.407919406039;7899.4865333753405;7596;10281.383117013003;10579.947100264488;10588.258821882471;10592.78814423711;10141.949290253575;10161.085502304828;10185.083342499174;10205.642802501914;10215.233857460684;10035.849462258071;10117.544710487971;10132.848007279898;10134.543945522433;10171.338862973957;9977.30158888878;10043.347182433119;10048.598056077748;10052.849207261897;10054.69835904924;39248.41127383063;39533.209335813124;39682;39725;39726;3601.8559257629663;3700.9259814803822;3707.4253490708984;3738.7943663098517;3748.9625103748886;27710.861445692844;27876.303092422648;28105.578416323773;28222.294442638893;28674.713252867412;3199.856006479714;3272.2146684795716;3281.0976981330327;3305.603327600679;3400.7619466637343;245577.99999999822;246553.7672311645;246564;246943;246972;53161;53382.733086335094;53504.73247633815;53542.732286338316;53606.463935360145;56435.71782141063;56542;56588.717056414454;56603.43396565982;56731;13504.02911359519;13985.790213146607;14007.159570425807;14009.369578368998;14012.089214201043;9522.476403775363;9742.561584728704;9775.973522780148;9855.507224638883;10053.497325133714;15183.240837958057;15528.689426211638;15537.922310388602;15622.140782257085;15820.367185312689;6178.289496707936;6186.536009799193;6222;6279.874402511924;6323.9683801580695;785.2108630825983;798.6166640012826;806.5846089264033;809.7975506123469;830.1117803949716;26672.466550668876;27967.72032279692;28328.575071373947;28473.430531389255;28527.28681782972;936.9969248558355;955.5174636808506;965.2490402353237;986.9259805514615;988.5106872098357;14336.441840540003;14933.104013758992;14994;15000.623488041456;15002.897855587005;347462;351388;351809;352624;352642;51764.48235517597;51769.48230517722;51933;52083.47916520862;52139.47860521422;1220.17638094286;1262.6275248801626;1264.7849865522883;1276.6616846535676;1278.1883503974857;25690;26479.867600661873;26566.203013909613;27271;27343.589846151925;42371;42799.7860010698;43030.569694302656;43088;43141.568584313754;286686;288102;289300;289328;291076;31013.8449307752;35147.29705405826;35945;37185.51257949652;37348.43977340397;41093.79453102715;41468.377974331015;41843.581564183965;41964.58035419607;42435.57564424316;35774.8211258942;36053.45919811152;36180.63819361878;36221.818890906434;36621;34333.82833085819;34501.827490862386;34720.82639586786;35058.82470587631;35110;34533.654663453046;34545.65454345424;35178.82410588033;35375.82312088423;35509.467357990165;30134.246643834078;32314;32379;32584.83707581447;32611;20960.53814778181;21018.908348073164;21216.528958667754;21519;21700.67448988235 12:00:49 INFO - PROCESS | 1048 | |2;jquery.html;348.56951664694117;353.4309761284333;360.6916086745832;366.5802655958927;381.6946442845723;13606.183628982255;14332.426702931883;16205.270762815671;18058.548536286566;18059.187336569852;17136.485905422825;17618.383356582523;17643.206055727493;17886.64226715465;17979.550511237227;4876.512348765122;5051.974740126294;5102.056119617882;5105.566026887719;5110.795568177268;3942.704297177723;4156.459660244164;5792.131180322943;6109.419605137505;6149.415805498493;5992.490638295728;6095;6236.656983865885;6238.937610623903;6246.968765156168;6111.572189946705;6131.9386806131815;6140.416660417253;6141.508679305667;6188.721507532149;5172.793088276502;5340.599455040849;5351.598630102758;5375.43557926416;5434.864128396822;22188.667169992463;22297.777022229733;22453;22519;22560.43598910024;2293.243229734184;2315.5368926214824;2358.8230882683697;2359.905603775847;2377.714674239085;12633.178843375152;13939.442422303096;13983.580492585137;14307.213103279315;14394.784078238834;1749.1079549429755;1771.4685594321782;1773.8758286919922;1782.0855082280798;1787.3297513432462;76143.61928190323;78951.605241974;78967.60516197383;80686.59656701738;81748.59125704392;9635.951820240994;9725.708228753148;9815.803683926282;9899.614054032441;10038.548265328078;13488.190708557519;13494.257815820225;13501.257430841202;13536.120152190077;13738.65653358674;7191.964040179766;7308.196098429168;7367.373773229228;7386.778396648109;7406.851862962711;6746.460283177334;6854.862902741917;6963.09479767627;7030.6484675766005;7061.611611361322;6340.714667839959;6442.903356449657;6485.740570377132;6552.57408268461;6567.605943643397;1759.5337235632567;1809.891406515587;1811.3750755989163;1814.609858880347;1816.5731053202487;840.6301227459898;850.6852464588018;857.5969294431607;857.8713193020972;860.3074525007304;17372.913135434494;18843.905780471283;19106.140223689967;19440.513987150436;19547;839.1901814748796;844.7550210438903;856.546030603781;860.4364141487335;863.7063398444434;9955.30312878106;10752.354858708502;10767.892321076846;10788.352698838095;10858.185636077167;490667;504912;510475;515381;549228.3464566929;93066;93247.53376233076;94968;96333;97710.51144744373;885.287343688337;925.121134921827;943.4905151218265;945.2721404518483;948.2840455456087;16090.597735056717;16401.917990409973;16771;16926;17190;39040.80479597584;39090.4136437948;39648.40527392034;39857.80071099626;40575.18849622932;53505.464945350046;54610;55639.44360556343;56616;57022.42977570171;7740.806479838049;7765.417593680384;7922.207779222032;7947.284744373034;8064.3548516119745;13073.346332683517;13186.208827470382;13371.665708356979;13869.306534673427;14077.366518506897;10629.468526573486;10714.89285107139;11114;11144;11427.542898284308;11137.665870023913;11193.104551635686;11416.429178540873;11576.24754390962;11704.70738231551;10816.351018938889;10910.945445272722;10944.23390362667;11111.777764444827;11571.305721656727;6797.150356205474;6940.9652951734915;7064.717411303491;7244.637768111468;7965.163657815846;8924.509151996443;10632.681019569425;10705.89294107049;10827.512761925575;10999.34003959765 12:00:49 INFO - PROCESS | 1048 | |3;mootools.html;1317.4022660913408;1341.22879344377;1343.6754414862282;1346.367207412516;1349.2309383651323;6554.41010309072;6897.551659142152;6906.309369063091;6907.30926907309;6948.861022779548;5813.73838177282;6019.277686677594;6593.175853018372;6769.120014398133;6772;2057.8456615753767;2068.7414073240843;2079.2930403662754;2085.4786303424144;2092.351371074964;1999.9800002000013;2046.498607841081;2056.1672522628282;2066.9793302067014;2083.4374718825907;1751.4307849948755;1808.159205969224;2000.949976250591;2064.5354795171115;2068.441520789386;2082.2920207129573;2100.684897265407;2100.9684854727193;2101.107029512451;2105.568358486522;2017.9192832286692;2089.63431399506;2120.650092734697;2120.989395053015;2121.374194612593;6886.414654754301;6951.339622735832;6956.965215173942;6958.8956165657555;6964.582125072512;4077.3272410052305;4181.163767246563;4187.476565429321;4189.476315460567;4189.958100419018;2573.575360065587;2695.757381835644;2724.482348353826;2736.3843135294596;2774.3202915285683;1006.5369929832286;1067.8878717734603;1087.2986923434419;1109.0905457524752;1110.217296805758;8934.955325223462;8943.821123577558;8948.105189481064;8962.327825413055;8991.775205619846;3563.1092226943265;3776.339140650375;3801.885943421702;3835.520559930009;3836.3094642963974;7528.698852045857;7535.095788505414;7540.962295188599;7544.170141284454;7566.924330756732;4220.556841531657;4233.872983810491;4314.913701725948;4316.330968699856;4321.114171594815;3767.9246415071543;3949.1311911379453;3957.445957565943;3958.4062390641056;3960.168364643457;3245.8863939761995;3252.772305938609;3275.737940964717;3304.1739565108724;3322.2691007978206;1097.1003776902862;1105.2318638546133;1109.0683825586384;1116.8157254053153;1121.7083558274744;637.5345997421815;652.8400541867178;660.7753363856312;662.6719773712043;676.4419354032942;4634.050019745944;4640.559146881041;4674.158651442773;4784.114938736345;4850.054239423349;861.3582880753878;867.6789587852535;868.5266529741306;897.9506127163069;911.2846415563884;3171.7304029157317;3174.5238214268043;3201.1196920846955;3208.262099717078;3304.1078908694517;7633.885491717629;8012.4791888528225;8012.839743205103;8014.559199244098;8031.55826429552;7926.48477848938;7934.444588878839;7990.680372785024;7991.160928102582;7992.280694737386;6326.367363263636;6379.234491860913;6382.074599183093;6414.454771344394;6417.2299324081405;4466.59800617946;4509.2559727644575;4584.701994370355;4599.333096700892;4604.447466304065;7159.463040271896;7229.710811567478;7300.16048154469;7344.30229128232;7370.96314518424;7436.73971410998;7444.404447644284;7486.401087912845;7516.3986881048295;7531.435142364235;5788.21859049023;5843.181954526454;5850.005499065254;5896.675682837314;5905.99598068338;6445.613263204223;6580.144581204413;6622.933770662232;6749.358810912955;6773.254941956282;6009.699515024144;6029.095635654687;6029.517638588814;6046.183765191643;6065.363136870565;5915.467607915309;5960.254968128984;5970.940290597038;5974.2233509643465;6100.298465676504;5425.972870135624;5480.588955828249;5539.750711218086;5543.143047079274;5551.861203469945;4142.233686767958;4157.48031496063;4256.638185754246;4275.700700950903;4374.606285434326;4128.071183983579;4161.459010328638;4228.260054490391;4231.4710661167355;4369.126174765123 12:00:49 INFO - PROCESS | 1048 | |4;prototype.html;332.69417536170533;333.7930483100478;334.2362701227695;339.3586122228988;339.923517208628;4801.951980480194;4818.494058123899;4848.903021939564;4852.781624826878;4861.319415281863;4749.715017098968;4752.952470475294;4757.690750101241;4770.594499467541;4777.18466982547;3678;3702.2040261343805;3760.6991440684815;3783.3946568549036;3865.400862866253;3839.6352346527037;3840.423936409547;3847.903802404934;3857.0935830079907;3865.4201869719627;3817.6945844332386;3827.4641550182996;3833.297525244696;3835.6931445484292;3859.4982652255167;3661.7253705972025;3727.683146932514;3761.811909404546;3843.5579908310624;3866.3233934061427;3432.845521951518;3449.6205417404067;3451.447768357076;3457.9827100864586;3460.74044446665;4688.273317635738;4698.624110071186;4703.059388122355;4706.976465117653;4728.777474586938;4271.914561708749;4302.741835489881;4394.6923715339935;4394.714343567658;4400.669949753749;4946.427857514109;5013.172826483626;5036.697798132123;5040.31955685985;5051.06555287273;3761.4733937248807;3766.510353654035;3785.5268091488565;3787.867424640152;3795.62043795621;5108.310378098983;5241.580673546107;5279.208118782175;5307.442718514539;5310.92033619496;3792.184680293695;3814.904627384338;3824.560175579844;3834.175652234783;3852.9422058669143;3868.7872167031082;3869.28418242626;3871.787051712183;3876.4960555127655;3913.373860182357;4323.956760432418;4329.242382583036;4376.321670141133;4389.6927215095275;4414.117176564636;4862.78117484714;5011.148104822116;5028.371453568304;5047.899042019139;5096.592272618182;1302.1145620977636;1309.4173092973535;1310.4823594680047;1313.159577870164;1318.2947123288993;888.3958907942532;932.5057719408725;943.1888575824785;948.8244674735196;951.5194826612517;4202.952859177072;4288.263253382385;4315.3095504719095;4424.845130420485;4442.267025940683;5163.845084647466;5188.81839135636;5188.92216616751;5194.194899790538;5196.4803519648485;4401.415490423445;4405.449318835145;4406.383106365111;4442.533533978885;4451.465824101128;2563.0260501009498;2639.9472010560066;2656.8804403801873;2665.640138581267;2670.919872403831;4634.83778067766;4647.814087436533;4652.1626107300335;4654.394928659321;4659.767011649404;5103.948960510422;5122.334096567422;5142.177251639794;5147.2279158126585;5164.1995490699;4411.2941929291155;4585.335126406652;4606.585407313358;4611.371518234641;4626.501689087855;5008.599312054954;5027.547520723152;5030.496950304941;5050.520200580939;5057.848264552069;4905.509449055208;4940.382452193476;4955.4053513577155;4960.479149689375;4960.77676504551;4572.611328037154;4795.937746768923;4804.807807687654;4813.181759101031;4820.156472617348;3594;3609.4766258892837;3614.1326081739667;3619.511365965561;3626.9637303627683;5172.948270517247;5195.5583775378;5208.921866172086;5212.582993360446;5228;4930.235813448921;4998.175301075282;5027.648064635587;5041.974790126026;5053.7220452875445;4994.451719966822;5003.649744517995;5005.6996580205305;5024.884571565506;5025.974870125626;4958.206686930074;4971.37857767779;4982.032097321465;4983.850484485326;5000.64995450315;4854.757262136808;4882.414110306786;4973;5031.773570189277;5074.771635276347;4788.964690006803;4929.5563399292805;4958.677685950402;4964.553190212898;4989.301497790385 12:00:49 INFO - PROCESS | 1048 | |5;yui.html;654.8690261947612;657.1982181738277;730.4412124724587;766.3754040457027;770.1759117744012;12567.685807854798;13677;13703.931480342584;13768.793468097974;13844.861551384496;12964;12994.675133121676;13204.273764942924;13211.801822972662;13220.53728119516;796.1799346672922;804.5414113955048;806.701520437436;811.736185739634;812.8618134917078;806.5040000399741;807.3783186946048;807.6850028488894;808.1030056637128;808.1998821167032;850.2730165708323;851.5018714052287;852.0967774159379;852.1691350932822;852.4288726553224;845.6406027438314;845.9154084591522;846.272205902923;846.4371193156544;846.4582667093003;622.8131560531822;622.844288927768;623.2178615837141;623.4295619508144;623.4731651754261;12074.577389791402;12087.637370878887;12165.635030949086;13171.473141074346;13509.256990865499;12571.434285457177;13154.60536183916;13253.586615268396;13331.866681333257;13631.522896698569;10311.926605504586;10645.308054976402;10768.138548916068;10841.078508326802;10852.511636976355;3233.692799184074;3237.0936137881195;3304.653011433789;3308.8841890533718;3330.5170750241323;62412.37587624111;64011.359886401005;65135.348646513405;65725.34274657287;66418;9880.40717556949;9965.402075875325;10014.54934527948;10042.397456152654;10254.846787051256;17756.11219439023;17931.820681793277;17954.102294885466;17967.73048404275;18115;6349.714262858182;6484.513661475408;6544.803655890331;6611.900821487681;6674.733010679518;3372.780769249991;3473.4442489201606;3501.2472318451655;3509.508668786372;3533.293341331744;2721.360480287131;2756.4349308391734;2786.5402208635755;2798.608194852722;2806.3825958289144;445.72810585542794;446.85030514777264;454.71807479362343;455.52625269719783;458.8531669865611;609.9664518451527;625.2278436131375;631.6462780842743;641.4547634510722;645.4062262718311;8114.270319352234;8294.502329860228;8381.95809020951;8386.371022173362;8421.115782842835;626.5175814622806;647.7441410642864;648.6464876642241;653.5621133840243;654.9148610680676;3822.3119838428793;3959.8020098994934;4005;4057.3102572562143;4089.672826173899;67582;67957;69651.3034869655;69836.65081674559;70643.64678176577;45467.54532454699;58158.418415816144;60444;60555.394446055856;60822.69588652028;51195.48804511982;51464.742676286376;51899.22151167736;53065;53529.1970620441;7302.342789149002;7346.265373462611;7566.886496702664;7622.885656715264;7882.172371900868;37052.44421333628;38410.231795364496;38753.612463875;38776.6122338773;38933.415998759476;45563.77218114021;45853.77073114613;47346.28980565363;47425.52574474349;47543.76228118837;10218.846717299395;10230.181585472996;10414.166866650801;10809.405482698527;11077.224594278645;12819.615411537668;12987.935060324638;13557.932210338886;13990.930045349709;14000.649983750487;12042.57919024035;12320.383980800745;12392.442340094898;12514;12649.810252846399;11873.643790686294;12177.634670959886;12352.197107188003;12655.493780248687;12883.227006379273;11526.88473115258;11700.414979251174;12573.685657858627;12695.42870570808;13143.737125257632;9423.952880235554;9834;9886.802263954825;10206.948965255126;10218.182545396201;10074.395536267848;10208.183345332503;10328.896711032794;10632.202584806326;10855.782884342427 12:00:49 INFO - PROCESS | 1048 | __end_tp_report 12:00:49 INFO - PROCESS | 1048 | __start_cc_report 12:00:49 INFO - PROCESS | 1048 | _x_x_mozilla_cycle_collect,1121 12:00:49 INFO - PROCESS | 1048 | __end_cc_report 12:00:49 INFO - PROCESS | 1048 | __startTimestamp1483646449449__endTimestamp 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | ------- Summary: start ------- 12:00:49 INFO - PROCESS | 1048 | Number of tests: 6 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | [#0] dojo.html Cycles:165 Average:4712.62 Median:4834.32 stddev:503.28 (10.4%) stddev-sans-first:475.61 12:00:49 INFO - PROCESS | 1048 | Values: 2558.7 2568.4 2589.4 2592.4 2595.8 4632.8 4686.1 4727.5 4744.0 4750.5 4727.9 4757.8 4773.3 4791.3 4880.3 4395.2 4411.1 4439.8 4461.8 4467.8 3958.1 4402.3 4405.0 4436.7 4453.7 4492.4 4500.4 4503.7 4517.3 4522.0 4320.3 4336.8 4351.3 4402.9 4426.5 4331.7 4339.1 4373.5 4400.6 4407.9 4723.1 4745.6 4762.5 4763.1 4790.7 5071.2 5084.5 5102.6 5106.8 5155.1 4976.8 5004.1 5061.1 5092.4 5103.1 4804.9 4818.7 4819.9 4844.2 4860.3 5232.1 5232.9 5253.9 5255.0 5269.6 4899.5 4928.7 4966.3 4997.0 5016.1 5099.3 5101.1 5104.8 5137.7 5156.1 4952.3 4966.4 4967.6 4980.9 5056.9 4993.6 4994.8 5037.4 5088.8 5094.7 4232.5 4251.5 4302.5 4316.7 4360.1 3733.0 3765.1 3789.5 3803.8 3805.0 4389.0 4390.1 4397.9 4419.5 4451.6 4992.5 5034.9 5138.8 5141.9 5177.4 4380.2 4402.2 4408.5 4408.5 4462.9 4620.3 4620.5 4623.7 4628.3 4673.6 4613.0 4800.8 4803.7 4804.5 4830.4 4985.3 5086.4 5130.2 5140.7 5201.1 4781.4 4787.3 4790.7 4791.3 4834.3 5080.6 5087.3 5088.9 5089.0 5129.6 4929.1 4977.4 5089.5 5142.5 5147.5 5027.1 5030.0 5034.8 5036.9 5061.9 4813.7 4858.6 5021.8 5032.9 5051.6 4836.3 4880.0 4914.9 4917.2 4993.2 5039.8 5045.8 5047.2 5052.9 5092.5 4856.6 4926.7 4932.6 4934.4 4970.9 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | [#1] ext.html Cycles:180 Average:45914.90 Median:35163.06 stddev:78243.48 (222.5%) stddev-sans-first:78388.80 12:00:49 INFO - PROCESS | 1048 | Values: 537.4 537.5 548.4 569.7 573.3 31672.4 42357.8 44520.0 44572.6 44741.3 41816.2 42333.6 42345.6 42345.8 42428.6 7635.4 7724.6 7892.9 7894.4 7899.5 7596.0 10281.4 10579.9 10588.3 10592.8 10141.9 10161.1 10185.1 10205.6 10215.2 10035.8 10117.5 10132.8 10134.5 10171.3 9977.3 10043.3 10048.6 10052.8 10054.7 39248.4 39533.2 39682.0 39725.0 39726.0 3601.9 3700.9 3707.4 3738.8 3749.0 27710.9 27876.3 28105.6 28222.3 28674.7 3199.9 3272.2 3281.1 3305.6 3400.8 245578.0 246553.8 246564.0 246943.0 246972.0 53161.0 53382.7 53504.7 53542.7 53606.5 56435.7 56542.0 56588.7 56603.4 56731.0 13504.0 13985.8 14007.2 14009.4 14012.1 9522.5 9742.6 9776.0 9855.5 10053.5 15183.2 15528.7 15537.9 15622.1 15820.4 6178.3 6186.5 6222.0 6279.9 6324.0 785.2 798.6 806.6 809.8 830.1 26672.5 27967.7 28328.6 28473.4 28527.3 937.0 955.5 965.2 986.9 988.5 14336.4 14933.1 14994.0 15000.6 15002.9 347462.0 351388.0 351809.0 352624.0 352642.0 51764.5 51769.5 51933.0 52083.5 52139.5 1220.2 1262.6 1264.8 1276.7 1278.2 25690.0 26479.9 26566.2 27271.0 27343.6 42371.0 42799.8 43030.6 43088.0 43141.6 286686.0 288102.0 289300.0 289328.0 291076.0 31013.8 35147.3 35945.0 37185.5 37348.4 41093.8 41468.4 41843.6 41964.6 42435.6 35774.8 36053.5 36180.6 36221.8 36621.0 34333.8 34501.8 34720.8 35058.8 35110.0 34533.7 34545.7 35178.8 35375.8 35509.5 30134.2 32314.0 32379.0 32584.8 32611.0 20960.5 21018.9 21216.5 21519.0 21700.7 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | [#2] jquery.html Cycles:180 Average:29255.50 Median:4516.49 stddev:84772.47 (1877.0%) stddev-sans-first:84982.50 12:00:49 INFO - PROCESS | 1048 | Values: 348.6 353.4 360.7 366.6 381.7 13606.2 14332.4 16205.3 18058.5 18059.2 17136.5 17618.4 17643.2 17886.6 17979.6 4876.5 5052.0 5102.1 5105.6 5110.8 3942.7 4156.5 5792.1 6109.4 6149.4 5992.5 6095.0 6236.7 6238.9 6247.0 6111.6 6131.9 6140.4 6141.5 6188.7 5172.8 5340.6 5351.6 5375.4 5434.9 22188.7 22297.8 22453.0 22519.0 22560.4 2293.2 2315.5 2358.8 2359.9 2377.7 12633.2 13939.4 13983.6 14307.2 14394.8 1749.1 1771.5 1773.9 1782.1 1787.3 76143.6 78951.6 78967.6 80686.6 81748.6 9636.0 9725.7 9815.8 9899.6 10038.5 13488.2 13494.3 13501.3 13536.1 13738.7 7192.0 7308.2 7367.4 7386.8 7406.9 6746.5 6854.9 6963.1 7030.6 7061.6 6340.7 6442.9 6485.7 6552.6 6567.6 1759.5 1809.9 1811.4 1814.6 1816.6 840.6 850.7 857.6 857.9 860.3 17372.9 18843.9 19106.1 19440.5 19547.0 839.2 844.8 856.5 860.4 863.7 9955.3 10752.4 10767.9 10788.4 10858.2 490667.0 504912.0 510475.0 515381.0 549228.3 93066.0 93247.5 94968.0 96333.0 97710.5 885.3 925.1 943.5 945.3 948.3 16090.6 16401.9 16771.0 16926.0 17190.0 39040.8 39090.4 39648.4 39857.8 40575.2 53505.5 54610.0 55639.4 56616.0 57022.4 7740.8 7765.4 7922.2 7947.3 8064.4 13073.3 13186.2 13371.7 13869.3 14077.4 10629.5 10714.9 11114.0 11144.0 11427.5 11137.7 11193.1 11416.4 11576.2 11704.7 10816.4 10910.9 10944.2 11111.8 11571.3 6797.2 6941.0 7064.7 7244.6 7965.2 8924.5 10632.7 10705.9 10827.5 10999.3 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | [#3] mootools.html Cycles:180 Average:4503.71 Median:4637.30 stddev:2358.63 (50.9%) stddev-sans-first:2353.09 12:00:49 INFO - PROCESS | 1048 | Values: 1317.4 1341.2 1343.7 1346.4 1349.2 6554.4 6897.6 6906.3 6907.3 6948.9 5813.7 6019.3 6593.2 6769.1 6772.0 2057.8 2068.7 2079.3 2085.5 2092.4 2000.0 2046.5 2056.2 2067.0 2083.4 1751.4 1808.2 2000.9 2064.5 2068.4 2082.3 2100.7 2101.0 2101.1 2105.6 2017.9 2089.6 2120.7 2121.0 2121.4 6886.4 6951.3 6957.0 6958.9 6964.6 4077.3 4181.2 4187.5 4189.5 4190.0 2573.6 2695.8 2724.5 2736.4 2774.3 1006.5 1067.9 1087.3 1109.1 1110.2 8935.0 8943.8 8948.1 8962.3 8991.8 3563.1 3776.3 3801.9 3835.5 3836.3 7528.7 7535.1 7541.0 7544.2 7566.9 4220.6 4233.9 4314.9 4316.3 4321.1 3767.9 3949.1 3957.4 3958.4 3960.2 3245.9 3252.8 3275.7 3304.2 3322.3 1097.1 1105.2 1109.1 1116.8 1121.7 637.5 652.8 660.8 662.7 676.4 4634.1 4640.6 4674.2 4784.1 4850.1 861.4 867.7 868.5 898.0 911.3 3171.7 3174.5 3201.1 3208.3 3304.1 7633.9 8012.5 8012.8 8014.6 8031.6 7926.5 7934.4 7990.7 7991.2 7992.3 6326.4 6379.2 6382.1 6414.5 6417.2 4466.6 4509.3 4584.7 4599.3 4604.4 7159.5 7229.7 7300.2 7344.3 7371.0 7436.7 7444.4 7486.4 7516.4 7531.4 5788.2 5843.2 5850.0 5896.7 5906.0 6445.6 6580.1 6622.9 6749.4 6773.3 6009.7 6029.1 6029.5 6046.2 6065.4 5915.5 5960.3 5970.9 5974.2 6100.3 5426.0 5480.6 5539.8 5543.1 5551.9 4142.2 4157.5 4256.6 4275.7 4374.6 4128.1 4161.5 4228.3 4231.5 4369.1 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | [#4] prototype.html Cycles:180 Average:4178.08 Median:4674.02 stddev:1178.44 (25.2%) stddev-sans-first:1145.85 12:00:49 INFO - PROCESS | 1048 | Values: 332.7 333.8 334.2 339.4 339.9 4802.0 4818.5 4848.9 4852.8 4861.3 4749.7 4753.0 4757.7 4770.6 4777.2 3678.0 3702.2 3760.7 3783.4 3865.4 3839.6 3840.4 3847.9 3857.1 3865.4 3817.7 3827.5 3833.3 3835.7 3859.5 3661.7 3727.7 3761.8 3843.6 3866.3 3432.8 3449.6 3451.4 3458.0 3460.7 4688.3 4698.6 4703.1 4707.0 4728.8 4271.9 4302.7 4394.7 4394.7 4400.7 4946.4 5013.2 5036.7 5040.3 5051.1 3761.5 3766.5 3785.5 3787.9 3795.6 5108.3 5241.6 5279.2 5307.4 5310.9 3792.2 3814.9 3824.6 3834.2 3852.9 3868.8 3869.3 3871.8 3876.5 3913.4 4324.0 4329.2 4376.3 4389.7 4414.1 4862.8 5011.1 5028.4 5047.9 5096.6 1302.1 1309.4 1310.5 1313.2 1318.3 888.4 932.5 943.2 948.8 951.5 4203.0 4288.3 4315.3 4424.8 4442.3 5163.8 5188.8 5188.9 5194.2 5196.5 4401.4 4405.4 4406.4 4442.5 4451.5 2563.0 2639.9 2656.9 2665.6 2670.9 4634.8 4647.8 4652.2 4654.4 4659.8 5103.9 5122.3 5142.2 5147.2 5164.2 4411.3 4585.3 4606.6 4611.4 4626.5 5008.6 5027.5 5030.5 5050.5 5057.8 4905.5 4940.4 4955.4 4960.5 4960.8 4572.6 4795.9 4804.8 4813.2 4820.2 3594.0 3609.5 3614.1 3619.5 3627.0 5172.9 5195.6 5208.9 5212.6 5228.0 4930.2 4998.2 5027.6 5042.0 5053.7 4994.5 5003.6 5005.7 5024.9 5026.0 4958.2 4971.4 4982.0 4983.9 5000.6 4854.8 4882.4 4973.0 5031.8 5074.8 4789.0 4929.6 4958.7 4964.6 4989.3 12:00:49 INFO - PROCESS | 1048 | 12:00:49 INFO - PROCESS | 1048 | [#5] yui.html Cycles:180 Average:15087.87 Median:446.29 stddev:18957.67 (4247.8%) stddev-sans-first:18979.88 12:00:49 INFO - PROCESS | 1048 | Values: 654.9 657.2 730.4 766.4 770.2 12567.7 13677.0 13703.9 13768.8 13844.9 12964.0 12994.7 13204.3 13211.8 13220.5 796.2 804.5 806.7 811.7 812.9 806.5 807.4 807.7 808.1 808.2 850.3 851.5 852.1 852.2 852.4 845.6 845.9 846.3 846.4 846.5 622.8 622.8 623.2 623.4 623.5 12074.6 12087.6 12165.6 13171.5 13509.3 12571.4 13154.6 13253.6 13331.9 13631.5 10311.9 10645.3 10768.1 10841.1 10852.5 3233.7 3237.1 3304.7 3308.9 3330.5 62412.4 64011.4 65135.3 65725.3 66418.0 9880.4 9965.4 10014.5 10042.4 10254.8 17756.1 17931.8 17954.1 17967.7 18115.0 6349.7 6484.5 6544.8 6611.9 6674.7 3372.8 3473.4 3501.2 3509.5 3533.3 2721.4 2756.4 2786.5 2798.6 2806.4 445.7 446.9 454.7 455.5 458.9 610.0 625.2 631.6 641.5 645.4 8114.3 8294.5 8382.0 8386.4 8421.1 626.5 647.7 648.6 653.6 654.9 3822.3 3959.8 4005.0 4057.3 4089.7 67582.0 67957.0 69651.3 69836.7 70643.6 45467.5 58158.4 60444.0 60555.4 60822.7 51195.5 51464.7 51899.2 53065.0 53529.2 7302.3 7346.3 7566.9 7622.9 7882.2 37052.4 38410.2 38753.6 38776.6 38933.4 45563.8 45853.8 47346.3 47425.5 47543.8 10218.8 10230.2 10414.2 10809.4 11077.2 12819.6 12987.9 13557.9 13990.9 14000.6 12042.6 12320.4 12392.4 12514.0 12649.8 11873.6 12177.6 12352.2 12655.5 12883.2 11526.9 11700.4 12573.7 12695.4 13143.7 9424.0 9834.0 9886.8 10206.9 10218.2 10074.4 10208.2 10328.9 10632.2 10855.8 12:00:49 INFO - PROCESS | 1048 | -------- Summary: end -------- 12:00:49 INFO - PROCESS | 1048 | 12:00:50 INFO - TEST-INFO | 1048: exit 0 12:00:50 INFO - TEST-OK | dromaeo_css | took 1121268ms 12:00:50 INFO - TEST-START | kraken 12:00:50 INFO - operating with platform_type : linux_ 12:00:50 INFO - Initialising browser for kraken test... 12:00:50 INFO - TEST-INFO | started process 1425 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph9soNU/profile http://localhost:58081/getInfo.html) 12:00:50 INFO - PROCESS | 1425 | 1483646450750 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:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 12:00:54 INFO - PROCESS | 1425 | __metrics Screen width/height:1600/1200 12:00:54 INFO - PROCESS | 1425 | colorDepth:24 12:00:54 INFO - PROCESS | 1425 | Browser inner width/height: 1024/697 12:00:54 INFO - PROCESS | 1425 | __metrics 12:00:54 INFO - PROCESS | 1425 | 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" 12:00:54 INFO - PROCESS | 1425 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:00:55 INFO - PROCESS | 1425 | console.error: PushService: 12:00:55 INFO - PROCESS | 1425 | stateChangeProcessEnqueue: Error transitioning state 12:00:55 INFO - PROCESS | 1425 | UnknownError 12:00:55 INFO - TEST-INFO | 1425: exit 0 12:00:55 INFO - Browser initialized. 12:00:55 INFO - Running cycle 1/1 for kraken test... 12:00:55 INFO - TEST-INFO | started process 1506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph9soNU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:01:07 INFO - PROCESS | 1506 | RSS: Main: 151236608 12:01:07 INFO - PROCESS | 1506 | 12:01:10 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58081/tests/kraken/driver.html?testName=audio-beat-detection) 12:01:11 INFO - PROCESS | 1506 | RSS: Main: 205893632 12:01:11 INFO - PROCESS | 1506 | 12:01:14 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58081/tests/kraken/driver.html?testName=audio-dft) 12:01:14 INFO - PROCESS | 1506 | RSS: Main: 184942592 12:01:14 INFO - PROCESS | 1506 | 12:01:18 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58081/tests/kraken/driver.html?testName=audio-fft) 12:01:18 INFO - PROCESS | 1506 | RSS: Main: 267702272 12:01:18 INFO - PROCESS | 1506 | 12:01:20 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58081/tests/kraken/driver.html?testName=audio-oscillator) 12:01:21 INFO - PROCESS | 1506 | RSS: Main: 179175424 12:01:21 INFO - PROCESS | 1506 | 12:01:23 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58081/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:01:24 INFO - PROCESS | 1506 | RSS: Main: 181878784 12:01:24 INFO - PROCESS | 1506 | 12:01:27 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58081/tests/kraken/driver.html?testName=imaging-darkroom) 12:01:27 INFO - PROCESS | 1506 | RSS: Main: 221155328 12:01:27 INFO - PROCESS | 1506 | 12:01:31 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58081/tests/kraken/driver.html?testName=imaging-desaturate) 12:01:32 INFO - PROCESS | 1506 | RSS: Main: 215334912 12:01:32 INFO - PROCESS | 1506 | 12:01:35 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58081/tests/kraken/driver.html?testName=json-parse-financial) 12:01:35 INFO - PROCESS | 1506 | RSS: Main: 217276416 12:01:35 INFO - PROCESS | 1506 | 12:01:37 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58081/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:01:37 INFO - PROCESS | 1506 | RSS: Main: 180895744 12:01:37 INFO - PROCESS | 1506 | 12:01:39 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:01:40 INFO - PROCESS | 1506 | RSS: Main: 187785216 12:01:40 INFO - PROCESS | 1506 | 12:01:42 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:01:43 INFO - PROCESS | 1506 | RSS: Main: 196055040 12:01:43 INFO - PROCESS | 1506 | 12:01:46 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:01:46 INFO - PROCESS | 1506 | RSS: Main: 199905280 12:01:46 INFO - PROCESS | 1506 | 12:01:49 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:01:50 INFO - PROCESS | 1506 | RSS: Main: 202133504 12:01:50 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | Cycle 1(1): loaded http://localhost:58081/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58081/tests/kraken/driver.html?testName=ai-astar) 12:01:52 INFO - PROCESS | 1506 | RSS: Main: 254062592 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | __start_tp_report 12:01:52 INFO - PROCESS | 1506 | _x_x_mozilla_page_load 12:01:52 INFO - PROCESS | 1506 | _x_x_mozilla_page_load_details 12:01:52 INFO - PROCESS | 1506 | |i|pagename|runs| 12:01:52 INFO - PROCESS | 1506 | |0;ai-astar;112;114;116;115;115;116;115;115;115;115 12:01:52 INFO - PROCESS | 1506 | |1;audio-beat-detection;149;127;124;170;125;125;126;130;150;125 12:01:52 INFO - PROCESS | 1506 | |2;audio-dft;161;161;161;161;162;161;163;162;160;162 12:01:52 INFO - PROCESS | 1506 | |3;audio-fft;77;76;77;111;76;77;77;76;95;76 12:01:52 INFO - PROCESS | 1506 | |4;audio-oscillator;86;85;115;91;85;85;99;85;85;108 12:01:52 INFO - PROCESS | 1506 | |5;imaging-gaussian-blur;118;117;117;117;116;119;119;119;118;117 12:01:52 INFO - PROCESS | 1506 | |6;imaging-darkroom;149;150;149;148;148;149;149;149;149;149 12:01:52 INFO - PROCESS | 1506 | |7;imaging-desaturate;96;96;95;95;95;95;95;95;96;96 12:01:52 INFO - PROCESS | 1506 | |8;json-parse-financial;72;63;63;63;65;65;65;67;66;65 12:01:52 INFO - PROCESS | 1506 | |9;json-stringify-tinderbox;66;67;67;66;67;67;68;68;77;67 12:01:52 INFO - PROCESS | 1506 | |10;stanford-crypto-aes;84;84;83;83;84;84;84;95;83;94 12:01:52 INFO - PROCESS | 1506 | |11;stanford-crypto-ccm;144;132;133;133;131;135;132;145;226;133 12:01:52 INFO - PROCESS | 1506 | |12;stanford-crypto-pbkdf2;190;180;184;179;180;176;184;179;179;182 12:01:52 INFO - PROCESS | 1506 | |13;stanford-crypto-sha256-iterative;68;68;69;70;69;72;71;68;67;67 12:01:52 INFO - PROCESS | 1506 | __end_tp_report 12:01:52 INFO - PROCESS | 1506 | __start_cc_report 12:01:52 INFO - PROCESS | 1506 | _x_x_mozilla_cycle_collect,1874 12:01:52 INFO - PROCESS | 1506 | __end_cc_report 12:01:52 INFO - PROCESS | 1506 | __startTimestamp1483646512595__endTimestamp 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | ------- Summary: start ------- 12:01:52 INFO - PROCESS | 1506 | Number of tests: 14 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:1.14 (1.0%) stddev-sans-first:0.60 12:01:52 INFO - PROCESS | 1506 | Values: 112.0 114.0 116.0 115.0 115.0 116.0 115.0 115.0 115.0 115.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:128.50 stddev:15.77 (12.3%) stddev-sans-first:15.90 12:01:52 INFO - PROCESS | 1506 | Values: 149.0 127.0 124.0 170.0 125.0 125.0 126.0 130.0 150.0 125.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 12:01:52 INFO - PROCESS | 1506 | Values: 161.0 161.0 161.0 161.0 162.0 161.0 163.0 162.0 160.0 162.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#3] audio-fft Cycles:10 Average:81.80 Median:77.00 stddev:11.80 (15.3%) stddev-sans-first:12.39 12:01:52 INFO - PROCESS | 1506 | Values: 77.0 76.0 77.0 111.0 76.0 77.0 77.0 76.0 95.0 76.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:85.00 stddev:11.13 (13.1%) stddev-sans-first:11.56 12:01:52 INFO - PROCESS | 1506 | Values: 86.0 85.0 115.0 91.0 85.0 85.0 99.0 85.0 85.0 108.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:1.06 (0.9%) stddev-sans-first:1.12 12:01:52 INFO - PROCESS | 1506 | Values: 118.0 117.0 117.0 117.0 116.0 119.0 119.0 119.0 118.0 117.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:01:52 INFO - PROCESS | 1506 | Values: 149.0 150.0 149.0 148.0 148.0 149.0 149.0 149.0 149.0 149.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 12:01:52 INFO - PROCESS | 1506 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:65.00 stddev:2.67 (4.1%) stddev-sans-first:1.41 12:01:52 INFO - PROCESS | 1506 | Values: 72.0 63.0 63.0 63.0 65.0 65.0 65.0 67.0 66.0 65.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:67.00 stddev:3.23 (4.8%) stddev-sans-first:3.35 12:01:52 INFO - PROCESS | 1506 | Values: 66.0 67.0 67.0 66.0 67.0 67.0 68.0 68.0 77.0 67.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:84.00 stddev:4.61 (5.5%) stddev-sans-first:4.85 12:01:52 INFO - PROCESS | 1506 | Values: 84.0 84.0 83.0 83.0 84.0 84.0 84.0 95.0 83.0 94.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:134.00 stddev:29.11 (21.7%) stddev-sans-first:30.87 12:01:52 INFO - PROCESS | 1506 | Values: 144.0 132.0 133.0 133.0 131.0 135.0 132.0 145.0 226.0 133.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:181.00 stddev:3.92 (2.2%) stddev-sans-first:2.60 12:01:52 INFO - PROCESS | 1506 | Values: 190.0 180.0 184.0 179.0 180.0 176.0 184.0 179.0 179.0 182.0 12:01:52 INFO - PROCESS | 1506 | 12:01:52 INFO - PROCESS | 1506 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.66 (2.4%) stddev-sans-first:1.73 12:01:52 INFO - PROCESS | 1506 | Values: 68.0 68.0 69.0 70.0 69.0 72.0 71.0 68.0 67.0 67.0 12:01:52 INFO - PROCESS | 1506 | -------- Summary: end -------- 12:01:52 INFO - PROCESS | 1506 | 12:01:53 INFO - TEST-INFO | 1506: exit 0 12:01:53 INFO - TEST-OK | kraken | took 63509ms 12:01:53 INFO - SUITE-END | took 1184s 12:01:54 INFO - Completed test suite (00:19:45) 12:01:54 INFO - javascript benchmark 12:01:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2558.7185409604945, 2568.409265868849, 2589.4432696970134, 2592.4426248356613, 2595.7793587545057, 4632.768361581916, 4686.062787442516, 4727.52724727527, 4744.0037592105655, 4750.477447480779, 4727.881802954919, 4757.809687612491, 4773.284007398892, 4791.329213910055, 4880.341153944217, 4395.208862404766, 4411.073674528337, 4439.77801109944, 4461.776911154446, 4467.798949047301, 3958.1163313414795, 4402.25161722508, 4405.0, 4436.711613745112, 4453.688241823073, 4492.415985921825, 4500.39244701966, 4503.684742068057, 4517.299818528117, 4521.954780452186, 4320.265554855682, 4336.783160841946, 4351.303791393362, 4402.8679139625865, 4426.535213802569, 4331.740095594275, 4339.06710057336, 4373.540778218305, 4400.647948164139, 4407.867763967086, 4723.149833030053, 4745.6440766942615, 4762.523747625244, 4763.071201115784, 4790.688605240648, 5071.1632580624155, 5084.466131056222, 5102.566281866046, 5106.846794596168, 5155.072087024334, 4976.776045077981, 5004.099262132815, 5061.063703214917, 5092.388913330424, 5103.081445339837, 4804.927926081112, 4818.6626936114135, 4819.879503012383, 4844.152273352184, 4860.295257187688, 5232.058229518723, 5232.947670523246, 5253.921191182136, 5254.97372513135, 5269.578433725293, 4899.485554016848, 4928.704277743347, 4966.279889416014, 4996.975015124974, 5016.122178618727, 5099.286099946011, 5101.081805275012, 5104.795808167706, 5137.6660517066275, 5156.097682905476, 4952.331435256267, 4966.354373931438, 4967.552920237196, 4980.90038199234, 5056.924146137737, 4993.625478089158, 4994.775235114428, 5037.4458809530925, 5088.796448142033, 5094.719790411563, 4232.513260974965, 4251.532331443539, 4302.548232435612, 4316.676249281317, 4360.106178233455, 3733.029412352808, 3765.1151979284846, 3789.488419063391, 3803.7527560708463, 3805.0, 4389.0, 4390.084088675312, 4397.934030989538, 4419.513853476115, 4451.554844515587, 4992.525710057539, 5034.949650503521, 5138.820141295037, 5141.948580514147, 5177.404598471224, 4380.189664912002, 4402.185595664812, 4408.493023302297, 4408.537103604075, 4462.933056004163, 4620.330052142488, 4620.514845941195, 4623.745693986796, 4628.282616194495, 4673.602743766818, 4612.953870461387, 4800.783964721526, 4803.651608742059, 4804.495527969513, 4830.396200474941, 4985.252212168204, 5086.415062267886, 5130.179171332718, 5140.742962851918, 5201.141811601044, 4781.378420805273, 4787.257975013878, 4790.664653474363, 4791.281307803857, 4834.3231947528075, 5080.618953578422, 5087.2623469595965, 5088.898222035613, 5088.974555127349, 5129.563987060992, 4929.112759703145, 4977.402711674622, 5089.541941225308, 5142.485751424828, 5147.510986456281, 5027.095122877917, 5029.974850125726, 5034.823781167642, 5036.949630503648, 5061.8734531637, 4813.711177329372, 4858.562729354375, 5021.84934451967, 5032.874178145576, 5051.646384753032, 4836.25038119078, 4880.023995201045, 4914.852554423373, 4917.188663870565, 4993.151164302005, 5039.823606173766, 5045.7981680732355, 5047.192449208109, 5052.9494705052475, 5092.541671249605, 4856.5871900887405, 4926.704397736147, 4932.605391568594, 4934.358533390637, 4970.8757281068265], "value": 4679.305416619705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.3578573604542, 537.5135502370355, 548.4077196627643, 569.6553585081026, 573.3377948469517, 31672.366552668962, 42357.78821105898, 44519.999999999956, 44572.554274457245, 44741.32888006674, 41816.16367672645, 42333.57666423335, 42345.576544234624, 42345.7882710586, 42428.57571424285, 7635.350995165418, 7724.61376931154, 7892.921070789304, 7894.407919406039, 7899.4865333753405, 7596.0, 10281.383117013003, 10579.947100264488, 10588.258821882471, 10592.78814423711, 10141.949290253575, 10161.085502304828, 10185.083342499174, 10205.642802501914, 10215.233857460684, 10035.849462258071, 10117.544710487971, 10132.848007279898, 10134.543945522433, 10171.338862973957, 9977.30158888878, 10043.347182433119, 10048.598056077748, 10052.849207261897, 10054.69835904924, 39248.41127383063, 39533.209335813124, 39682.0, 39725.0, 39726.0, 3601.8559257629663, 3700.9259814803822, 3707.4253490708984, 3738.7943663098517, 3748.9625103748886, 27710.861445692844, 27876.303092422648, 28105.578416323773, 28222.294442638893, 28674.713252867412, 3199.856006479714, 3272.2146684795716, 3281.0976981330327, 3305.603327600679, 3400.7619466637343, 245577.99999999822, 246553.7672311645, 246564.0, 246943.0, 246972.0, 53161.0, 53382.733086335094, 53504.73247633815, 53542.732286338316, 53606.463935360145, 56435.71782141063, 56542.0, 56588.717056414454, 56603.43396565982, 56731.0, 13504.02911359519, 13985.790213146607, 14007.159570425807, 14009.369578368998, 14012.089214201043, 9522.476403775363, 9742.561584728704, 9775.973522780148, 9855.507224638883, 10053.497325133714, 15183.240837958057, 15528.689426211638, 15537.922310388602, 15622.140782257085, 15820.367185312689, 6178.289496707936, 6186.536009799193, 6222.0, 6279.874402511924, 6323.9683801580695, 785.2108630825983, 798.6166640012826, 806.5846089264033, 809.7975506123469, 830.1117803949716, 26672.466550668876, 27967.72032279692, 28328.575071373947, 28473.430531389255, 28527.28681782972, 936.9969248558355, 955.5174636808506, 965.2490402353237, 986.9259805514615, 988.5106872098357, 14336.441840540003, 14933.104013758992, 14994.0, 15000.623488041456, 15002.897855587005, 347462.0, 351388.0, 351809.0, 352624.0, 352642.0, 51764.48235517597, 51769.48230517722, 51933.0, 52083.47916520862, 52139.47860521422, 1220.17638094286, 1262.6275248801626, 1264.7849865522883, 1276.6616846535676, 1278.1883503974857, 25690.0, 26479.867600661873, 26566.203013909613, 27271.0, 27343.589846151925, 42371.0, 42799.7860010698, 43030.569694302656, 43088.0, 43141.568584313754, 286686.0, 288102.0, 289300.0, 289328.0, 291076.0, 31013.8449307752, 35147.29705405826, 35945.0, 37185.51257949652, 37348.43977340397, 41093.79453102715, 41468.377974331015, 41843.581564183965, 41964.58035419607, 42435.57564424316, 35774.8211258942, 36053.45919811152, 36180.63819361878, 36221.818890906434, 36621.0, 34333.82833085819, 34501.827490862386, 34720.82639586786, 35058.82470587631, 35110.0, 34533.654663453046, 34545.65454345424, 35178.82410588033, 35375.82312088423, 35509.467357990165, 30134.246643834078, 32314.0, 32379.0, 32584.83707581447, 32611.0, 20960.53814778181, 21018.908348073164, 21216.528958667754, 21519.0, 21700.67448988235], "value": 17846.03063355093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.56951664694117, 353.4309761284333, 360.6916086745832, 366.5802655958927, 381.6946442845723, 13606.183628982255, 14332.426702931883, 16205.270762815671, 18058.548536286566, 18059.187336569852, 17136.485905422825, 17618.383356582523, 17643.206055727493, 17886.64226715465, 17979.550511237227, 4876.512348765122, 5051.974740126294, 5102.056119617882, 5105.566026887719, 5110.795568177268, 3942.704297177723, 4156.459660244164, 5792.131180322943, 6109.419605137505, 6149.415805498493, 5992.490638295728, 6095.0, 6236.656983865885, 6238.937610623903, 6246.968765156168, 6111.572189946705, 6131.9386806131815, 6140.416660417253, 6141.508679305667, 6188.721507532149, 5172.793088276502, 5340.599455040849, 5351.598630102758, 5375.43557926416, 5434.864128396822, 22188.667169992463, 22297.777022229733, 22453.0, 22519.0, 22560.43598910024, 2293.243229734184, 2315.5368926214824, 2358.8230882683697, 2359.905603775847, 2377.714674239085, 12633.178843375152, 13939.442422303096, 13983.580492585137, 14307.213103279315, 14394.784078238834, 1749.1079549429755, 1771.4685594321782, 1773.8758286919922, 1782.0855082280798, 1787.3297513432462, 76143.61928190323, 78951.605241974, 78967.60516197383, 80686.59656701738, 81748.59125704392, 9635.951820240994, 9725.708228753148, 9815.803683926282, 9899.614054032441, 10038.548265328078, 13488.190708557519, 13494.257815820225, 13501.257430841202, 13536.120152190077, 13738.65653358674, 7191.964040179766, 7308.196098429168, 7367.373773229228, 7386.778396648109, 7406.851862962711, 6746.460283177334, 6854.862902741917, 6963.09479767627, 7030.6484675766005, 7061.611611361322, 6340.714667839959, 6442.903356449657, 6485.740570377132, 6552.57408268461, 6567.605943643397, 1759.5337235632567, 1809.891406515587, 1811.3750755989163, 1814.609858880347, 1816.5731053202487, 840.6301227459898, 850.6852464588018, 857.5969294431607, 857.8713193020972, 860.3074525007304, 17372.913135434494, 18843.905780471283, 19106.140223689967, 19440.513987150436, 19547.0, 839.1901814748796, 844.7550210438903, 856.546030603781, 860.4364141487335, 863.7063398444434, 9955.30312878106, 10752.354858708502, 10767.892321076846, 10788.352698838095, 10858.185636077167, 490667.0, 504912.0, 510475.0, 515381.0, 549228.3464566929, 93066.0, 93247.53376233076, 94968.0, 96333.0, 97710.51144744373, 885.287343688337, 925.121134921827, 943.4905151218265, 945.2721404518483, 948.2840455456087, 16090.597735056717, 16401.917990409973, 16771.0, 16926.0, 17190.0, 39040.80479597584, 39090.4136437948, 39648.40527392034, 39857.80071099626, 40575.18849622932, 53505.464945350046, 54610.0, 55639.44360556343, 56616.0, 57022.42977570171, 7740.806479838049, 7765.417593680384, 7922.207779222032, 7947.284744373034, 8064.3548516119745, 13073.346332683517, 13186.208827470382, 13371.665708356979, 13869.306534673427, 14077.366518506897, 10629.468526573486, 10714.89285107139, 11114.0, 11144.0, 11427.542898284308, 11137.665870023913, 11193.104551635686, 11416.429178540873, 11576.24754390962, 11704.70738231551, 10816.351018938889, 10910.945445272722, 10944.23390362667, 11111.777764444827, 11571.305721656727, 6797.150356205474, 6940.9652951734915, 7064.717411303491, 7244.637768111468, 7965.163657815846, 8924.509151996443, 10632.681019569425, 10705.89294107049, 10827.512761925575, 10999.34003959765], "value": 8844.061517659993, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.4022660913408, 1341.22879344377, 1343.6754414862282, 1346.367207412516, 1349.2309383651323, 6554.41010309072, 6897.551659142152, 6906.309369063091, 6907.30926907309, 6948.861022779548, 5813.73838177282, 6019.277686677594, 6593.175853018372, 6769.120014398133, 6772.0, 2057.8456615753767, 2068.7414073240843, 2079.2930403662754, 2085.4786303424144, 2092.351371074964, 1999.9800002000013, 2046.498607841081, 2056.1672522628282, 2066.9793302067014, 2083.4374718825907, 1751.4307849948755, 1808.159205969224, 2000.949976250591, 2064.5354795171115, 2068.441520789386, 2082.2920207129573, 2100.684897265407, 2100.9684854727193, 2101.107029512451, 2105.568358486522, 2017.9192832286692, 2089.63431399506, 2120.650092734697, 2120.989395053015, 2121.374194612593, 6886.414654754301, 6951.339622735832, 6956.965215173942, 6958.8956165657555, 6964.582125072512, 4077.3272410052305, 4181.163767246563, 4187.476565429321, 4189.476315460567, 4189.958100419018, 2573.575360065587, 2695.757381835644, 2724.482348353826, 2736.3843135294596, 2774.3202915285683, 1006.5369929832286, 1067.8878717734603, 1087.2986923434419, 1109.0905457524752, 1110.217296805758, 8934.955325223462, 8943.821123577558, 8948.105189481064, 8962.327825413055, 8991.775205619846, 3563.1092226943265, 3776.339140650375, 3801.885943421702, 3835.520559930009, 3836.3094642963974, 7528.698852045857, 7535.095788505414, 7540.962295188599, 7544.170141284454, 7566.924330756732, 4220.556841531657, 4233.872983810491, 4314.913701725948, 4316.330968699856, 4321.114171594815, 3767.9246415071543, 3949.1311911379453, 3957.445957565943, 3958.4062390641056, 3960.168364643457, 3245.8863939761995, 3252.772305938609, 3275.737940964717, 3304.1739565108724, 3322.2691007978206, 1097.1003776902862, 1105.2318638546133, 1109.0683825586384, 1116.8157254053153, 1121.7083558274744, 637.5345997421815, 652.8400541867178, 660.7753363856312, 662.6719773712043, 676.4419354032942, 4634.050019745944, 4640.559146881041, 4674.158651442773, 4784.114938736345, 4850.054239423349, 861.3582880753878, 867.6789587852535, 868.5266529741306, 897.9506127163069, 911.2846415563884, 3171.7304029157317, 3174.5238214268043, 3201.1196920846955, 3208.262099717078, 3304.1078908694517, 7633.885491717629, 8012.4791888528225, 8012.839743205103, 8014.559199244098, 8031.55826429552, 7926.48477848938, 7934.444588878839, 7990.680372785024, 7991.160928102582, 7992.280694737386, 6326.367363263636, 6379.234491860913, 6382.074599183093, 6414.454771344394, 6417.2299324081405, 4466.59800617946, 4509.2559727644575, 4584.701994370355, 4599.333096700892, 4604.447466304065, 7159.463040271896, 7229.710811567478, 7300.16048154469, 7344.30229128232, 7370.96314518424, 7436.73971410998, 7444.404447644284, 7486.401087912845, 7516.3986881048295, 7531.435142364235, 5788.21859049023, 5843.181954526454, 5850.005499065254, 5896.675682837314, 5905.99598068338, 6445.613263204223, 6580.144581204413, 6622.933770662232, 6749.358810912955, 6773.254941956282, 6009.699515024144, 6029.095635654687, 6029.517638588814, 6046.183765191643, 6065.363136870565, 5915.467607915309, 5960.254968128984, 5970.940290597038, 5974.2233509643465, 6100.298465676504, 5425.972870135624, 5480.588955828249, 5539.750711218086, 5543.143047079274, 5551.861203469945, 4142.233686767958, 4157.48031496063, 4256.638185754246, 4275.700700950903, 4374.606285434326, 4128.071183983579, 4161.459010328638, 4228.260054490391, 4231.4710661167355, 4369.126174765123], "value": 3717.8813314311788, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.69417536170533, 333.7930483100478, 334.2362701227695, 339.3586122228988, 339.923517208628, 4801.951980480194, 4818.494058123899, 4848.903021939564, 4852.781624826878, 4861.319415281863, 4749.715017098968, 4752.952470475294, 4757.690750101241, 4770.594499467541, 4777.18466982547, 3678.0, 3702.2040261343805, 3760.6991440684815, 3783.3946568549036, 3865.400862866253, 3839.6352346527037, 3840.423936409547, 3847.903802404934, 3857.0935830079907, 3865.4201869719627, 3817.6945844332386, 3827.4641550182996, 3833.297525244696, 3835.6931445484292, 3859.4982652255167, 3661.7253705972025, 3727.683146932514, 3761.811909404546, 3843.5579908310624, 3866.3233934061427, 3432.845521951518, 3449.6205417404067, 3451.447768357076, 3457.9827100864586, 3460.74044446665, 4688.273317635738, 4698.624110071186, 4703.059388122355, 4706.976465117653, 4728.777474586938, 4271.914561708749, 4302.741835489881, 4394.6923715339935, 4394.714343567658, 4400.669949753749, 4946.427857514109, 5013.172826483626, 5036.697798132123, 5040.31955685985, 5051.06555287273, 3761.4733937248807, 3766.510353654035, 3785.5268091488565, 3787.867424640152, 3795.62043795621, 5108.310378098983, 5241.580673546107, 5279.208118782175, 5307.442718514539, 5310.92033619496, 3792.184680293695, 3814.904627384338, 3824.560175579844, 3834.175652234783, 3852.9422058669143, 3868.7872167031082, 3869.28418242626, 3871.787051712183, 3876.4960555127655, 3913.373860182357, 4323.956760432418, 4329.242382583036, 4376.321670141133, 4389.6927215095275, 4414.117176564636, 4862.78117484714, 5011.148104822116, 5028.371453568304, 5047.899042019139, 5096.592272618182, 1302.1145620977636, 1309.4173092973535, 1310.4823594680047, 1313.159577870164, 1318.2947123288993, 888.3958907942532, 932.5057719408725, 943.1888575824785, 948.8244674735196, 951.5194826612517, 4202.952859177072, 4288.263253382385, 4315.3095504719095, 4424.845130420485, 4442.267025940683, 5163.845084647466, 5188.81839135636, 5188.92216616751, 5194.194899790538, 5196.4803519648485, 4401.415490423445, 4405.449318835145, 4406.383106365111, 4442.533533978885, 4451.465824101128, 2563.0260501009498, 2639.9472010560066, 2656.8804403801873, 2665.640138581267, 2670.919872403831, 4634.83778067766, 4647.814087436533, 4652.1626107300335, 4654.394928659321, 4659.767011649404, 5103.948960510422, 5122.334096567422, 5142.177251639794, 5147.2279158126585, 5164.1995490699, 4411.2941929291155, 4585.335126406652, 4606.585407313358, 4611.371518234641, 4626.501689087855, 5008.599312054954, 5027.547520723152, 5030.496950304941, 5050.520200580939, 5057.848264552069, 4905.509449055208, 4940.382452193476, 4955.4053513577155, 4960.479149689375, 4960.77676504551, 4572.611328037154, 4795.937746768923, 4804.807807687654, 4813.181759101031, 4820.156472617348, 3594.0, 3609.4766258892837, 3614.1326081739667, 3619.511365965561, 3626.9637303627683, 5172.948270517247, 5195.5583775378, 5208.921866172086, 5212.582993360446, 5228.0, 4930.235813448921, 4998.175301075282, 5027.648064635587, 5041.974790126026, 5053.7220452875445, 4994.451719966822, 5003.649744517995, 5005.6996580205305, 5024.884571565506, 5025.974870125626, 4958.206686930074, 4971.37857767779, 4982.032097321465, 4983.850484485326, 5000.64995450315, 4854.757262136808, 4882.414110306786, 4973.0, 5031.773570189277, 5074.771635276347, 4788.964690006803, 4929.5563399292805, 4958.677685950402, 4964.553190212898, 4989.301497790385], "value": 3818.0848512446037, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.8690261947612, 657.1982181738277, 730.4412124724587, 766.3754040457027, 770.1759117744012, 12567.685807854798, 13677.0, 13703.931480342584, 13768.793468097974, 13844.861551384496, 12964.0, 12994.675133121676, 13204.273764942924, 13211.801822972662, 13220.53728119516, 796.1799346672922, 804.5414113955048, 806.701520437436, 811.736185739634, 812.8618134917078, 806.5040000399741, 807.3783186946048, 807.6850028488894, 808.1030056637128, 808.1998821167032, 850.2730165708323, 851.5018714052287, 852.0967774159379, 852.1691350932822, 852.4288726553224, 845.6406027438314, 845.9154084591522, 846.272205902923, 846.4371193156544, 846.4582667093003, 622.8131560531822, 622.844288927768, 623.2178615837141, 623.4295619508144, 623.4731651754261, 12074.577389791402, 12087.637370878887, 12165.635030949086, 13171.473141074346, 13509.256990865499, 12571.434285457177, 13154.60536183916, 13253.586615268396, 13331.866681333257, 13631.522896698569, 10311.926605504586, 10645.308054976402, 10768.138548916068, 10841.078508326802, 10852.511636976355, 3233.692799184074, 3237.0936137881195, 3304.653011433789, 3308.8841890533718, 3330.5170750241323, 62412.37587624111, 64011.359886401005, 65135.348646513405, 65725.34274657287, 66418.0, 9880.40717556949, 9965.402075875325, 10014.54934527948, 10042.397456152654, 10254.846787051256, 17756.11219439023, 17931.820681793277, 17954.102294885466, 17967.73048404275, 18115.0, 6349.714262858182, 6484.513661475408, 6544.803655890331, 6611.900821487681, 6674.733010679518, 3372.780769249991, 3473.4442489201606, 3501.2472318451655, 3509.508668786372, 3533.293341331744, 2721.360480287131, 2756.4349308391734, 2786.5402208635755, 2798.608194852722, 2806.3825958289144, 445.72810585542794, 446.85030514777264, 454.71807479362343, 455.52625269719783, 458.8531669865611, 609.9664518451527, 625.2278436131375, 631.6462780842743, 641.4547634510722, 645.4062262718311, 8114.270319352234, 8294.502329860228, 8381.95809020951, 8386.371022173362, 8421.115782842835, 626.5175814622806, 647.7441410642864, 648.6464876642241, 653.5621133840243, 654.9148610680676, 3822.3119838428793, 3959.8020098994934, 4005.0, 4057.3102572562143, 4089.672826173899, 67582.0, 67957.0, 69651.3034869655, 69836.65081674559, 70643.64678176577, 45467.54532454699, 58158.418415816144, 60444.0, 60555.394446055856, 60822.69588652028, 51195.48804511982, 51464.742676286376, 51899.22151167736, 53065.0, 53529.1970620441, 7302.342789149002, 7346.265373462611, 7566.886496702664, 7622.885656715264, 7882.172371900868, 37052.44421333628, 38410.231795364496, 38753.612463875, 38776.6122338773, 38933.415998759476, 45563.77218114021, 45853.77073114613, 47346.28980565363, 47425.52574474349, 47543.76228118837, 10218.846717299395, 10230.181585472996, 10414.166866650801, 10809.405482698527, 11077.224594278645, 12819.615411537668, 12987.935060324638, 13557.932210338886, 13990.930045349709, 14000.649983750487, 12042.57919024035, 12320.383980800745, 12392.442340094898, 12514.0, 12649.810252846399, 11873.643790686294, 12177.634670959886, 12352.197107188003, 12655.493780248687, 12883.227006379273, 11526.88473115258, 11700.414979251174, 12573.685657858627, 12695.42870570808, 13143.737125257632, 9423.952880235554, 9834.0, 9886.802263954825, 10206.948965255126, 10218.182545396201, 10074.395536267848, 10208.183345332503, 10328.896711032794, 10632.202584806326, 10855.782884342427], "value": 6342.49019003587, "unit": "score"}], "name": "dromaeo_css", "value": 6365.100448437443, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 116.0, 115.0, 115.0, 116.0, 115.0, 115.0, 115.0, 115.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 127.0, 124.0, 170.0, 125.0, 125.0, 126.0, 130.0, 150.0, 125.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 163.0, 162.0, 160.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 111.0, 76.0, 77.0, 77.0, 76.0, 95.0, 76.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 115.0, 91.0, 85.0, 85.0, 99.0, 85.0, 85.0, 108.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 116.0, 119.0, 119.0, 119.0, 118.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 148.0, 148.0, 149.0, 149.0, 149.0, 149.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 63.0, 63.0, 63.0, 65.0, 65.0, 65.0, 67.0, 66.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 67.0, 67.0, 68.0, 68.0, 77.0, 67.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 83.0, 84.0, 84.0, 84.0, 95.0, 83.0, 94.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 132.0, 133.0, 133.0, 131.0, 135.0, 132.0, 145.0, 226.0, 133.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 180.0, 184.0, 179.0, 180.0, 176.0, 184.0, 179.0, 179.0, 182.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 70.0, 69.0, 72.0, 71.0, 68.0, 67.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1561.3000000000002, "alertThreshold": 2.0}]} 12:01:54 INFO - Return code: 0 12:01:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:01:54 INFO - # TBPL SUCCESS # 12:01:54 INFO - Running post-action listener: _resource_record_post_action 12:01:54 INFO - [mozharness: 2017-01-05 20:01:54.414168Z] Finished run-tests step (success) 12:01:54 INFO - Running post-run listener: _resource_record_post_run 12:01:54 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 390328320; Read time: 8; Write time: 240636 12:01:54 INFO - TinderboxPrint: CPU usage
13.1% 12:01:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:01:54 INFO - TinderboxPrint: I/O write bytes / time
390,328,320 / 240,636 12:01:54 INFO - TinderboxPrint: CPU idle
8,338.8 (86.9%) 12:01:54 INFO - TinderboxPrint: CPU user
1,211.5 (12.6%) 12:01:54 INFO - TinderboxPrint: Swap in / out
0 / 0 12:01:54 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108343296; Read time: 0; Write time: 74212 12:01:54 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 281985024; Read time: 0; Write time: 166424 12:01:55 INFO - Running post-run listener: _upload_blobber_files 12:01:55 INFO - Blob upload gear active. 12:01:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:01:55 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 12:01:55 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:01:55 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:01:55 INFO - (blobuploader) - INFO - Open directory for files ... 12:01:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:01:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:01:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:01:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:01:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:01:56 INFO - (blobuploader) - INFO - Done attempting. 12:01:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:01:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:01:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:01:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:01:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:01:57 INFO - (blobuploader) - INFO - Done attempting. 12:01:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:01:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:01:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:00 INFO - (blobuploader) - INFO - Done attempting. 12:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:02:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:01 INFO - (blobuploader) - INFO - Done attempting. 12:02:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:02:02 INFO - Return code: 0 12:02:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:02:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:02:02 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cc3ecb1e92531f53aaa241e0ff933bfb8dc321dda659fd739b23074951d0211269c8f601303b7df83b837992879dedf2c4f8c0196ec79bbd5a6aed7102f18f8", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/81da7c04799fc4a3ac512256c1aca20c8f8222f054be8b505428abf8f276563a41f32bf16197ec445d02920f96b8b08ec8630946249403ba904cab4cef7d51ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80348df1e9c5427e0c3f3c7f09947f9573d6b52540e03a63dda815e0e76414742651cedebe389dda52bd3dafed5e6ced69e4cd89722d073fefa014c198340c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f63b9cbd8e39a7fee9ff3a140b866609772bfbefd9f4400f0879dee854e846ac02758b3a5775d423bd2c48a7a14cdbede8c20570d7986e34dfbb8f26a710567b"} 12:02:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:02:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:02:02 INFO - Contents: 12:02:02 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cc3ecb1e92531f53aaa241e0ff933bfb8dc321dda659fd739b23074951d0211269c8f601303b7df83b837992879dedf2c4f8c0196ec79bbd5a6aed7102f18f8", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/81da7c04799fc4a3ac512256c1aca20c8f8222f054be8b505428abf8f276563a41f32bf16197ec445d02920f96b8b08ec8630946249403ba904cab4cef7d51ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80348df1e9c5427e0c3f3c7f09947f9573d6b52540e03a63dda815e0e76414742651cedebe389dda52bd3dafed5e6ced69e4cd89722d073fefa014c198340c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f63b9cbd8e39a7fee9ff3a140b866609772bfbefd9f4400f0879dee854e846ac02758b3a5775d423bd2c48a7a14cdbede8c20570d7986e34dfbb8f26a710567b"} 12:02:02 INFO - Running post-run listener: copy_logs_to_upload_dir 12:02:02 INFO - Copying logs to upload dir... 12:02:02 INFO - mkdir: /builds/slave/test/build/upload/logs 12:02:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.726145 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-05 12:02:01.894430) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 12:02:01.897666) ========= 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=00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cc3ecb1e92531f53aaa241e0ff933bfb8dc321dda659fd739b23074951d0211269c8f601303b7df83b837992879dedf2c4f8c0196ec79bbd5a6aed7102f18f8", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/81da7c04799fc4a3ac512256c1aca20c8f8222f054be8b505428abf8f276563a41f32bf16197ec445d02920f96b8b08ec8630946249403ba904cab4cef7d51ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80348df1e9c5427e0c3f3c7f09947f9573d6b52540e03a63dda815e0e76414742651cedebe389dda52bd3dafed5e6ced69e4cd89722d073fefa014c198340c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f63b9cbd8e39a7fee9ff3a140b866609772bfbefd9f4400f0879dee854e846ac02758b3a5775d423bd2c48a7a14cdbede8c20570d7986e34dfbb8f26a710567b"} build_url:https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/CKdDg2IjSg--zTlF_0xv_g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cc3ecb1e92531f53aaa241e0ff933bfb8dc321dda659fd739b23074951d0211269c8f601303b7df83b837992879dedf2c4f8c0196ec79bbd5a6aed7102f18f8", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/81da7c04799fc4a3ac512256c1aca20c8f8222f054be8b505428abf8f276563a41f32bf16197ec445d02920f96b8b08ec8630946249403ba904cab4cef7d51ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/80348df1e9c5427e0c3f3c7f09947f9573d6b52540e03a63dda815e0e76414742651cedebe389dda52bd3dafed5e6ced69e4cd89722d073fefa014c198340c98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f63b9cbd8e39a7fee9ff3a140b866609772bfbefd9f4400f0879dee854e846ac02758b3a5775d423bd2c48a7a14cdbede8c20570d7986e34dfbb8f26a710567b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 12:02:01.922857) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 12:02:01.923234) ========= 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=00204705a9daa57ed339a427000001b9-1483645271.617994-1810387669 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 12:02:02.010978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 12:02:02.011454) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 12:02:02.011987) ========= ========= Total master_lag: 0.38 =========