builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-040 starttime: 1483147827.55 results: success (0) revision: 81463aec62d02fa1915e03cda7a8ddc8d44c71fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.554908) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.555373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.555650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.570081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.570401) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-30 17:30:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-12-30 17:30:27 (46.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140893 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.724844) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.725174) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015078 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.774222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:27.774569) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 81463aec62d02fa1915e03cda7a8ddc8d44c71fc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 81463aec62d02fa1915e03cda7a8ddc8d44c71fc --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=9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-30 17:30:27,894 truncating revision to first 12 chars 2016-12-30 17:30:27,894 Setting DEBUG logging. 2016-12-30 17:30:27,894 attempt 1/10 2016-12-30 17:30:27,894 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/81463aec62d0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 17:30:28,376 unpacking tar archive at: mozilla-central-81463aec62d0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.794223 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:28.582810) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:28.583116) ========= script_repo_revision: 81463aec62d02fa1915e03cda7a8ddc8d44c71fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:28.583496) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:28.583790) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 17:30:28.593682) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-30 17:30:28.593992) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:30:28 INFO - MultiFileLogger online at 20161230 17:30:28 in /builds/slave/test 17:30:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:30:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:30:28 INFO - {'append_to_log': False, 17:30:28 INFO - 'base_work_dir': '/builds/slave/test', 17:30:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:30:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:30:28 INFO - 'branch': 'Firefox-Non-PGO', 17:30:28 INFO - 'buildbot_json_path': 'buildprops.json', 17:30:28 INFO - 'config_files': ('talos/linux_config.py',), 17:30:28 INFO - 'default_actions': ('clobber', 17:30:28 INFO - 'read-buildbot-config', 17:30:28 INFO - 'download-and-extract', 17:30:28 INFO - 'populate-webroot', 17:30:28 INFO - 'create-virtualenv', 17:30:28 INFO - 'install', 17:30:28 INFO - 'run-tests'), 17:30:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:30:28 INFO - 'download_minidump_stackwalk': True, 17:30:28 INFO - 'download_symbols': 'ondemand', 17:30:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:30:28 INFO - 'tooltool.py': '/tools/tooltool.py', 17:30:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:30:28 INFO - '/tools/misc-python/virtualenv.py')}, 17:30:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:30:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:30:28 INFO - 'installer_path': 'installer.exe', 17:30:28 INFO - 'log_level': 'info', 17:30:28 INFO - 'log_name': 'talos', 17:30:28 INFO - 'log_to_console': True, 17:30:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:30:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:30:28 INFO - 'opt_config_files': (), 17:30:28 INFO - 'pip_index': False, 17:30:28 INFO - 'sps_profile': False, 17:30:28 INFO - 'sps_profile_interval': 0, 17:30:28 INFO - 'suite': 'dromaeojs-e10s', 17:30:28 INFO - 'system_bits': '32', 17:30:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:30:28 INFO - 'title': 'talos-linux64-ix-040', 17:30:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:30:28 INFO - 'use_talos_json': True, 17:30:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:30:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:30:28 INFO - 'work_dir': 'build'} 17:30:28 INFO - [mozharness: 2016-12-31 01:30:28.812815Z] Running clobber step. 17:30:28 INFO - Running pre-action listener: _resource_record_pre_action 17:30:28 INFO - Running main action method: clobber 17:30:28 INFO - rmtree: /builds/slave/test/build 17:30:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:30:29 INFO - Running post-action listener: _resource_record_post_action 17:30:29 INFO - [mozharness: 2016-12-31 01:30:29.266554Z] Finished clobber step (success) 17:30:29 INFO - [mozharness: 2016-12-31 01:30:29.266654Z] Running read-buildbot-config step. 17:30:29 INFO - Running pre-action listener: _resource_record_pre_action 17:30:29 INFO - Running main action method: read_buildbot_config 17:30:29 INFO - Using buildbot properties: 17:30:29 INFO - { 17:30:29 INFO - "project": "", 17:30:29 INFO - "product": "firefox", 17:30:29 INFO - "who": "kwierso@gmail.com", 17:30:29 INFO - "installer_path": "public/build/target.tar.bz2", 17:30:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:30:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:30:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 17:30:29 INFO - "stage_platform": "linux64", 17:30:29 INFO - "basedir": "/builds/slave/test", 17:30:29 INFO - "buildnumber": 623, 17:30:29 INFO - "platform": "ubuntu64_hw", 17:30:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:30:29 INFO - "slavebuilddir": "test", 17:30:29 INFO - "taskId": "GOw1fnEUQHmUGUNwXdzYNA", 17:30:29 INFO - "branch": "mozilla-central", 17:30:29 INFO - "script_repo_revision": "production", 17:30:29 INFO - "revision": "81463aec62d02fa1915e03cda7a8ddc8d44c71fc", 17:30:29 INFO - "slavename": "talos-linux64-ix-040", 17:30:29 INFO - "repo_path": "mozilla-central" 17:30:29 INFO - } 17:30:29 INFO - Finding installer, test and symbols from parent task. 17:30:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOw1fnEUQHmUGUNwXdzYNA'}, attempt #1 17:30:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOw1fnEUQHmUGUNwXdzYNA'}, attempt #1 17:30:30 INFO - Task dependencies: UVhxwU_0RUeMWvx5RQXSXA 17:30:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA'}, attempt #1 17:30:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2 17:30:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/test_packages.json 17:30:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.crashreporter-symbols.zip 17:30:30 INFO - Running post-action listener: _resource_record_post_action 17:30:30 INFO - [mozharness: 2016-12-31 01:30:30.637588Z] Finished read-buildbot-config step (success) 17:30:30 INFO - [mozharness: 2016-12-31 01:30:30.637806Z] Running download-and-extract step. 17:30:30 INFO - Running pre-action listener: _resource_record_pre_action 17:30:30 INFO - Running main action method: download_and_extract 17:30:30 INFO - mkdir: /builds/slave/test/build/tests 17:30:30 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')]} 17:30:30 INFO - trying https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.test_packages.json 17:30:30 INFO - Downloading https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:30:31 INFO - Downloaded 896 bytes. 17:30:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:30:31 INFO - Using the following test package requirements: 17:30:31 INFO - {u'common': [u'target.common.tests.zip'], 17:30:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:30:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:30:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:30:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:30:31 INFO - u'mozbase': [u'target.common.tests.zip'], 17:30:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:30:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:30:31 INFO - u'web-platform': [u'target.common.tests.zip', 17:30:31 INFO - u'target.web-platform.tests.zip'], 17:30:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:30:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:30:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.common.tests.zip 17:30:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:30:31 INFO - Fetch https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.common.tests.zip into memory 17:30:31 INFO - Expected file size: 20246344 17:30:31 INFO - Obtained file size: 20246344 17:30:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:30:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.common.tests.zip 17:30:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:30:36 INFO - Fetch https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.common.tests.zip into memory 17:30:36 INFO - Expected file size: 20246344 17:30:36 INFO - Obtained file size: 20246344 17:30:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.talos.tests.zip 17:30:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:30:41 INFO - Fetch https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.talos.tests.zip into memory 17:30:41 INFO - Expected file size: 14409776 17:30:41 INFO - Obtained file size: 14409776 17:30:42 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')]} 17:30:42 INFO - trying https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2 17:30:42 INFO - Downloading https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:30:43 INFO - Downloaded 55807280 bytes. 17:30:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2 17:30:43 INFO - mkdir: /builds/slave/test/properties 17:30:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:30:43 INFO - Writing to file /builds/slave/test/properties/build_url 17:30:43 INFO - Contents: 17:30:43 INFO - build_url:https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2 17:30:43 INFO - Running post-action listener: _resource_record_post_action 17:30:43 INFO - Running post-action listener: set_extra_try_arguments 17:30:43 INFO - [mozharness: 2016-12-31 01:30:43.275193Z] Finished download-and-extract step (success) 17:30:43 INFO - [mozharness: 2016-12-31 01:30:43.275289Z] Running populate-webroot step. 17:30:43 INFO - Running pre-action listener: _resource_record_pre_action 17:30:43 INFO - Running main action method: populate_webroot 17:30:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:30:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:30:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:30:43 INFO - '--tppagecycles', 17:30:43 INFO - '7'], 17:30:43 INFO - 'tests': ['tsvgm']}}, 17:30:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:30:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:30:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:30:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:30:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['damp', 'tps']}, 17:30:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'tests': ['damp', 'tps']}, 17:30:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['dromaeo_dom']}, 17:30:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:30:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['basic_compositor_video']}, 17:30:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:30:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['a11yr', 17:30:43 INFO - 'ts_paint', 17:30:43 INFO - 'tpaint', 17:30:43 INFO - 'sessionrestore', 17:30:43 INFO - 'sessionrestore_no_auto_restore', 17:30:43 INFO - 'tabpaint']}, 17:30:43 INFO - 'other-e10s': {'tests': ['a11yr', 17:30:43 INFO - 'ts_paint', 17:30:43 INFO - 'tpaint', 17:30:43 INFO - 'sessionrestore', 17:30:43 INFO - 'sessionrestore_no_auto_restore', 17:30:43 INFO - 'tabpaint']}, 17:30:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:30:43 INFO - 'ts_paint', 17:30:43 INFO - 'tpaint', 17:30:43 INFO - 'sessionrestore', 17:30:43 INFO - 'sessionrestore_no_auto_restore', 17:30:43 INFO - 'tabpaint']}, 17:30:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:30:43 INFO - 'ts_paint', 17:30:43 INFO - 'tpaint', 17:30:43 INFO - 'sessionrestore', 17:30:43 INFO - 'sessionrestore_no_auto_restore', 17:30:43 INFO - 'tabpaint']}, 17:30:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['a11yr', 17:30:43 INFO - 'ts_paint', 17:30:43 INFO - 'tpaint', 17:30:43 INFO - 'sessionrestore', 17:30:43 INFO - 'sessionrestore_no_auto_restore', 17:30:43 INFO - 'tabpaint']}, 17:30:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['a11yr', 17:30:43 INFO - 'ts_paint', 17:30:43 INFO - 'tpaint', 17:30:43 INFO - 'sessionrestore', 17:30:43 INFO - 'sessionrestore_no_auto_restore', 17:30:43 INFO - 'tabpaint']}, 17:30:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['tsvgx', 17:30:43 INFO - 'tsvgr_opacity', 17:30:43 INFO - 'tart', 17:30:43 INFO - 'tscrollx', 17:30:43 INFO - 'cart', 17:30:43 INFO - 'tsvg_static']}, 17:30:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:30:43 INFO - 'tsvgr_opacity', 17:30:43 INFO - 'tart', 17:30:43 INFO - 'tscrollx', 17:30:43 INFO - 'cart', 17:30:43 INFO - 'tsvg_static']}, 17:30:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'talos_options': ['--disable-e10s'], 17:30:43 INFO - 'tests': ['tp5o']}, 17:30:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:30:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'talos_options': ['--disable-e10s', 17:30:43 INFO - '--xperf_path', 17:30:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:43 INFO - 'tests': ['tp5n']}, 17:30:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:30:43 INFO - 'talos_options': ['--xperf_path', 17:30:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:43 INFO - 'tests': ['tp5n']}}, 17:30:43 INFO - 'talos.zip': {'path': '', 17:30:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:30:43 INFO - Running post-action listener: _resource_record_post_action 17:30:43 INFO - [mozharness: 2016-12-31 01:30:43.288425Z] Finished populate-webroot step (success) 17:30:43 INFO - [mozharness: 2016-12-31 01:30:43.288515Z] Running create-virtualenv step. 17:30:43 INFO - Running pre-action listener: _resource_record_pre_action 17:30:43 INFO - Running main action method: create_virtualenv 17:30:43 INFO - Creating virtualenv /builds/slave/test/build/venv 17:30:43 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 17:30:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:30:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:30:43 INFO - Using real prefix '/usr' 17:30:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:30:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:30:45 INFO - Installing pip.................done. 17:30:45 INFO - Return code: 0 17:30:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:30:46 INFO - Reading from file tmpfile_stdout 17:30:46 INFO - Output received: 17:30:46 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) 17:30:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:46 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:46 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 17:30:46 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 17:30:46 INFO - Using env: {'DISPLAY': ':0', 17:30:46 INFO - 'HOME': '/home/cltbld', 17:30:46 INFO - 'LANG': 'en_US.UTF-8', 17:30:46 INFO - 'LANGUAGE': 'en_US:en', 17:30:46 INFO - 'LOGNAME': 'cltbld', 17:30:46 INFO - 'MAIL': '/var/mail/cltbld', 17:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:46 INFO - 'MOZ_NO_REMOTE': '1', 17:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:46 INFO - 'NO_EM_RESTART': '1', 17:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:46 INFO - 'PWD': '/builds/slave/test', 17:30:46 INFO - 'SHELL': '/bin/bash', 17:30:46 INFO - 'SHLVL': '1', 17:30:46 INFO - 'TERM': 'linux', 17:30:46 INFO - 'TMOUT': '86400', 17:30:46 INFO - 'USER': 'cltbld', 17:30:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:46 INFO - '_': '/tools/buildbot/bin/python'} 17:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:46 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 17:30:46 INFO - Cleaning up... 17:30:46 INFO - Return code: 0 17:30:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:46 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:46 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 17:30:46 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 17:30:46 INFO - Using env: {'DISPLAY': ':0', 17:30:46 INFO - 'HOME': '/home/cltbld', 17:30:46 INFO - 'LANG': 'en_US.UTF-8', 17:30:46 INFO - 'LANGUAGE': 'en_US:en', 17:30:46 INFO - 'LOGNAME': 'cltbld', 17:30:46 INFO - 'MAIL': '/var/mail/cltbld', 17:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:46 INFO - 'MOZ_NO_REMOTE': '1', 17:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:46 INFO - 'NO_EM_RESTART': '1', 17:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:46 INFO - 'PWD': '/builds/slave/test', 17:30:46 INFO - 'SHELL': '/bin/bash', 17:30:46 INFO - 'SHLVL': '1', 17:30:46 INFO - 'TERM': 'linux', 17:30:46 INFO - 'TMOUT': '86400', 17:30:46 INFO - 'USER': 'cltbld', 17:30:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:46 INFO - '_': '/tools/buildbot/bin/python'} 17:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:46 INFO - Downloading/unpacking psutil>=3.1.1 17:30:46 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 17:30:46 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 17:30:46 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 17:30:46 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 17:30:46 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 17:30:46 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 17:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:30:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:48 INFO - Installing collected packages: psutil 17:30:48 INFO - Running setup.py install for psutil 17:30:48 INFO - building 'psutil._psutil_linux' extension 17:30:48 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 17:30:48 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 17:30:48 INFO - building 'psutil._psutil_posix' extension 17:30:48 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 17:30:49 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 17:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:49 INFO - Successfully installed psutil 17:30:49 INFO - Cleaning up... 17:30:49 INFO - Return code: 0 17:30:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:30:49 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')]} 17:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:49 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')]} 17:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:49 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:49 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 17:30:49 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 17:30:49 INFO - Using env: {'DISPLAY': ':0', 17:30:49 INFO - 'HOME': '/home/cltbld', 17:30:49 INFO - 'LANG': 'en_US.UTF-8', 17:30:49 INFO - 'LANGUAGE': 'en_US:en', 17:30:49 INFO - 'LOGNAME': 'cltbld', 17:30:49 INFO - 'MAIL': '/var/mail/cltbld', 17:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:49 INFO - 'MOZ_NO_REMOTE': '1', 17:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:49 INFO - 'NO_EM_RESTART': '1', 17:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:49 INFO - 'PWD': '/builds/slave/test', 17:30:49 INFO - 'SHELL': '/bin/bash', 17:30:49 INFO - 'SHLVL': '1', 17:30:49 INFO - 'TERM': 'linux', 17:30:49 INFO - 'TMOUT': '86400', 17:30:49 INFO - 'USER': 'cltbld', 17:30:49 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:49 INFO - '_': '/tools/buildbot/bin/python'} 17:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:30: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 17:30: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 17:30: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 17:30: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 17:30: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 17:30: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 17:30:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:30:51 INFO - Installing collected packages: mozsystemmonitor 17:30:51 INFO - Running setup.py install for mozsystemmonitor 17:30:51 INFO - Successfully installed mozsystemmonitor 17:30:51 INFO - Cleaning up... 17:30:51 INFO - Return code: 0 17:30:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30: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')]} 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:30:51 INFO - Using env: {'DISPLAY': ':0', 17:30:51 INFO - 'HOME': '/home/cltbld', 17:30:51 INFO - 'LANG': 'en_US.UTF-8', 17:30:51 INFO - 'LANGUAGE': 'en_US:en', 17:30:51 INFO - 'LOGNAME': 'cltbld', 17:30:51 INFO - 'MAIL': '/var/mail/cltbld', 17:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:51 INFO - 'MOZ_NO_REMOTE': '1', 17:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:51 INFO - 'NO_EM_RESTART': '1', 17:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:51 INFO - 'PWD': '/builds/slave/test', 17:30:51 INFO - 'SHELL': '/bin/bash', 17:30:51 INFO - 'SHLVL': '1', 17:30:51 INFO - 'TERM': 'linux', 17:30:51 INFO - 'TMOUT': '86400', 17:30:51 INFO - 'USER': 'cltbld', 17:30:51 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:51 INFO - '_': '/tools/buildbot/bin/python'} 17:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:51 INFO - Downloading/unpacking jsonschema==2.5.1 17:30:51 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 17:30:51 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 17:30:51 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 17:30:51 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 17:30:51 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 17:30:51 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 17:30:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:30:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:30:53 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 17:30:53 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 17:30:53 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 17:30:53 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 17:30:53 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 17:30:53 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 17:30:54 INFO - Downloading functools32-3.2.3-2.tar.gz 17:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:30:54 INFO - warning: no files found matching '*.txt' 17:30:54 INFO - no previously-included directories found matching 'build' 17:30:54 INFO - no previously-included directories found matching 'dist' 17:30:54 INFO - no previously-included directories found matching '.git*' 17:30:54 INFO - Installing collected packages: jsonschema, functools32 17:30:54 INFO - Running setup.py install for functools32 17:30:54 INFO - warning: no files found matching '*.txt' 17:30:54 INFO - no previously-included directories found matching 'build' 17:30:54 INFO - no previously-included directories found matching 'dist' 17:30:54 INFO - no previously-included directories found matching '.git*' 17:30:54 INFO - Successfully installed jsonschema functools32 17:30:54 INFO - Cleaning up... 17:30:54 INFO - Return code: 0 17:30:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:30:54 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')]} 17:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:54 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')]} 17:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:54 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:54 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 17:30:54 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 17:30:54 INFO - Using env: {'DISPLAY': ':0', 17:30:54 INFO - 'HOME': '/home/cltbld', 17:30:54 INFO - 'LANG': 'en_US.UTF-8', 17:30:54 INFO - 'LANGUAGE': 'en_US:en', 17:30:54 INFO - 'LOGNAME': 'cltbld', 17:30:54 INFO - 'MAIL': '/var/mail/cltbld', 17:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:54 INFO - 'MOZ_NO_REMOTE': '1', 17:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:54 INFO - 'NO_EM_RESTART': '1', 17:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:54 INFO - 'PWD': '/builds/slave/test', 17:30:54 INFO - 'SHELL': '/bin/bash', 17:30:54 INFO - 'SHLVL': '1', 17:30:54 INFO - 'TERM': 'linux', 17:30:54 INFO - 'TMOUT': '86400', 17:30:54 INFO - 'USER': 'cltbld', 17:30:54 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:54 INFO - '_': '/tools/buildbot/bin/python'} 17:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:30:54 INFO - Cleaning up... 17:30:54 INFO - Return code: 0 17:30:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:30:54 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')]} 17:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:54 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')]} 17:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:54 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:54 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 17:30:54 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 17:30:54 INFO - Using env: {'DISPLAY': ':0', 17:30:54 INFO - 'HOME': '/home/cltbld', 17:30:54 INFO - 'LANG': 'en_US.UTF-8', 17:30:54 INFO - 'LANGUAGE': 'en_US:en', 17:30:54 INFO - 'LOGNAME': 'cltbld', 17:30:54 INFO - 'MAIL': '/var/mail/cltbld', 17:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:54 INFO - 'MOZ_NO_REMOTE': '1', 17:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:54 INFO - 'NO_EM_RESTART': '1', 17:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:54 INFO - 'PWD': '/builds/slave/test', 17:30:54 INFO - 'SHELL': '/bin/bash', 17:30:54 INFO - 'SHLVL': '1', 17:30:54 INFO - 'TERM': 'linux', 17:30:54 INFO - 'TMOUT': '86400', 17:30:54 INFO - 'USER': 'cltbld', 17:30:54 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:54 INFO - '_': '/tools/buildbot/bin/python'} 17:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:54 INFO - Downloading/unpacking blobuploader==1.2.4 17:30:54 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 17:30:54 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 17:30:54 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 17:30:54 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 17:30:54 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 17:30:54 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 17:30:56 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:56 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 17:30:56 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 17:30:56 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 17:30:56 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 17:30:56 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 17:30:56 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 17:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:57 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 17:30:57 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 17:30:57 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 17:30:57 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 17:30:57 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 17:30:57 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 17:30:57 INFO - Downloading docopt-0.6.1.tar.gz 17:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:57 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:57 INFO - Running setup.py install for blobuploader 17:30:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:57 INFO - Running setup.py install for requests 17:30:58 INFO - Running setup.py install for docopt 17:30:58 INFO - Successfully installed blobuploader requests docopt 17:30:58 INFO - Cleaning up... 17:30:58 INFO - Return code: 0 17:30:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:58 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')]} 17:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:58 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')]} 17:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:58 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:30:58 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 17:30:58 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 17:30:58 INFO - Using env: {'DISPLAY': ':0', 17:30:58 INFO - 'HOME': '/home/cltbld', 17:30:58 INFO - 'LANG': 'en_US.UTF-8', 17:30:58 INFO - 'LANGUAGE': 'en_US:en', 17:30:58 INFO - 'LOGNAME': 'cltbld', 17:30:58 INFO - 'MAIL': '/var/mail/cltbld', 17:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:58 INFO - 'MOZ_NO_REMOTE': '1', 17:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:58 INFO - 'NO_EM_RESTART': '1', 17:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:58 INFO - 'PWD': '/builds/slave/test', 17:30:58 INFO - 'SHELL': '/bin/bash', 17:30:58 INFO - 'SHLVL': '1', 17:30:58 INFO - 'TERM': 'linux', 17:30:58 INFO - 'TMOUT': '86400', 17:30:58 INFO - 'USER': 'cltbld', 17:30:58 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:58 INFO - '_': '/tools/buildbot/bin/python'} 17:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:58 INFO - Running setup.py (path:/tmp/pip-rdhzI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:58 INFO - Running setup.py (path:/tmp/pip-NNuh4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:58 INFO - Running setup.py (path:/tmp/pip-461dTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:58 INFO - Running setup.py (path:/tmp/pip-2smGQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:58 INFO - Running setup.py (path:/tmp/pip-cvvaJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:58 INFO - Running setup.py (path:/tmp/pip-74pLO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:58 INFO - Running setup.py (path:/tmp/pip-T6dH46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:58 INFO - Running setup.py (path:/tmp/pip-jgHVVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:58 INFO - Running setup.py (path:/tmp/pip-shBHGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:59 INFO - Running setup.py (path:/tmp/pip-OBbUhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:59 INFO - Running setup.py (path:/tmp/pip-blBnpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:59 INFO - Running setup.py (path:/tmp/pip-5wZl7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:59 INFO - Running setup.py (path:/tmp/pip-pn_n04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:59 INFO - Running setup.py (path:/tmp/pip-tE1Ckw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:59 INFO - Running setup.py (path:/tmp/pip-FyjKy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:59 INFO - Running setup.py (path:/tmp/pip-vPlg3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:59 INFO - Running setup.py (path:/tmp/pip-e7WVEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:59 INFO - Running setup.py install for manifestparser 17:30:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:59 INFO - Running setup.py install for mozcrash 17:30:59 INFO - Running setup.py install for mozdebug 17:30:59 INFO - Running setup.py install for mozdevice 17:31:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for mozfile 17:31:00 INFO - Running setup.py install for mozhttpd 17:31:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for mozinfo 17:31:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for mozInstall 17:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for mozleak 17:31:00 INFO - Running setup.py install for mozlog 17:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for moznetwork 17:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for mozprocess 17:31:00 INFO - Running setup.py install for mozprofile 17:31:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:31:00 INFO - Running setup.py install for mozrunner 17:31:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:31:01 INFO - Running setup.py install for mozscreenshot 17:31:01 INFO - Running setup.py install for moztest 17:31:01 INFO - Running setup.py install for mozversion 17:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:31:01 INFO - Cleaning up... 17:31:01 INFO - Return code: 0 17:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:31:01 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')]} 17:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:01 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')]} 17:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:01 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:31:01 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 17:31:01 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 17:31:01 INFO - Using env: {'DISPLAY': ':0', 17:31:01 INFO - 'HOME': '/home/cltbld', 17:31:01 INFO - 'LANG': 'en_US.UTF-8', 17:31:01 INFO - 'LANGUAGE': 'en_US:en', 17:31:01 INFO - 'LOGNAME': 'cltbld', 17:31:01 INFO - 'MAIL': '/var/mail/cltbld', 17:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:01 INFO - 'MOZ_NO_REMOTE': '1', 17:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:01 INFO - 'NO_EM_RESTART': '1', 17:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:01 INFO - 'PWD': '/builds/slave/test', 17:31:01 INFO - 'SHELL': '/bin/bash', 17:31:01 INFO - 'SHLVL': '1', 17:31:01 INFO - 'TERM': 'linux', 17:31:01 INFO - 'TMOUT': '86400', 17:31:01 INFO - 'USER': 'cltbld', 17:31:01 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:01 INFO - '_': '/tools/buildbot/bin/python'} 17:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:31:01 INFO - Running setup.py (path:/tmp/pip-uhblNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:31:01 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)) 17:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:31:01 INFO - Running setup.py (path:/tmp/pip-0ol9_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:31:01 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)) 17:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:31:01 INFO - Running setup.py (path:/tmp/pip-M9a6Tp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:31:01 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)) 17:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:31:01 INFO - Running setup.py (path:/tmp/pip-gospBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:31:01 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)) 17:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:31:01 INFO - Running setup.py (path:/tmp/pip-RKMNz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:31:02 INFO - Running setup.py (path:/tmp/pip-djiR00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:31:02 INFO - Running setup.py (path:/tmp/pip-pBsahv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:31:02 INFO - Running setup.py (path:/tmp/pip-Mq3pZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:31:02 INFO - Running setup.py (path:/tmp/pip-bGkadL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:31:02 INFO - Running setup.py (path:/tmp/pip-kS65EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:31:02 INFO - Running setup.py (path:/tmp/pip-QCF7Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:31:02 INFO - Running setup.py (path:/tmp/pip-HkiRQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:31:02 INFO - Running setup.py (path:/tmp/pip-QclEG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:31:02 INFO - Running setup.py (path:/tmp/pip-cySTrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:31:02 INFO - Running setup.py (path:/tmp/pip-dtQ_Le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:31:02 INFO - Running setup.py (path:/tmp/pip-OLukHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:31:02 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)) 17:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:31:02 INFO - Running setup.py (path:/tmp/pip-f_6SXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:31:02 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)) 17:31:02 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)) 17:31:02 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)) 17:31:02 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)) 17:31:02 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)) 17:31:02 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)) 17:31:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:31:02 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 17:31:02 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 17:31:02 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 17:31:02 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 17:31:02 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 17:31:02 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 17:31:04 INFO - Downloading blessings-1.6.tar.gz 17:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:31:05 INFO - Installing collected packages: blessings 17:31:05 INFO - Running setup.py install for blessings 17:31:05 INFO - Successfully installed blessings 17:31:05 INFO - Cleaning up... 17:31:05 INFO - Return code: 0 17:31:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:05 INFO - Reading from file tmpfile_stdout 17:31:05 INFO - Current package versions: 17:31:05 INFO - argparse == 1.2.1 17:31:05 INFO - blessings == 1.6 17:31:05 INFO - blobuploader == 1.2.4 17:31:05 INFO - docopt == 0.6.1 17:31:05 INFO - functools32 == 3.2.3-2 17:31:05 INFO - jsonschema == 2.5.1 17:31:05 INFO - manifestparser == 1.1 17:31:05 INFO - mozInstall == 1.12 17:31:05 INFO - mozcrash == 1.0 17:31:05 INFO - mozdebug == 0.1 17:31:05 INFO - mozdevice == 0.49 17:31:05 INFO - mozfile == 1.2 17:31:05 INFO - mozhttpd == 0.7 17:31:05 INFO - mozinfo == 0.9 17:31:05 INFO - mozleak == 0.1 17:31:05 INFO - mozlog == 3.4 17:31:05 INFO - moznetwork == 0.27 17:31:05 INFO - mozprocess == 0.23 17:31:05 INFO - mozprofile == 0.28 17:31:05 INFO - mozrunner == 6.13 17:31:05 INFO - mozscreenshot == 0.1 17:31:05 INFO - mozsystemmonitor == 0.3 17:31:05 INFO - moztest == 0.8 17:31:05 INFO - mozversion == 1.4 17:31:05 INFO - psutil == 3.1.1 17:31:05 INFO - requests == 1.2.3 17:31:05 INFO - wsgiref == 0.1.2 17:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:05 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:31:05 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 17:31:05 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 17:31:05 INFO - Using env: {'DISPLAY': ':0', 17:31:05 INFO - 'HOME': '/home/cltbld', 17:31:05 INFO - 'LANG': 'en_US.UTF-8', 17:31:05 INFO - 'LANGUAGE': 'en_US:en', 17:31:05 INFO - 'LOGNAME': 'cltbld', 17:31:05 INFO - 'MAIL': '/var/mail/cltbld', 17:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:05 INFO - 'MOZ_NO_REMOTE': '1', 17:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:05 INFO - 'NO_EM_RESTART': '1', 17:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:05 INFO - 'PWD': '/builds/slave/test', 17:31:05 INFO - 'SHELL': '/bin/bash', 17:31:05 INFO - 'SHLVL': '1', 17:31:05 INFO - 'TERM': 'linux', 17:31:05 INFO - 'TMOUT': '86400', 17:31:05 INFO - 'USER': 'cltbld', 17:31:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:05 INFO - '_': '/tools/buildbot/bin/python'} 17:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 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)) 17:31:05 INFO - Cleaning up... 17:31:05 INFO - Return code: 0 17:31:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:05 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 0x16b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a23480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', '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 17:31:05 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 17:31:05 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 17:31:05 INFO - Using env: {'DISPLAY': ':0', 17:31:05 INFO - 'HOME': '/home/cltbld', 17:31:05 INFO - 'LANG': 'en_US.UTF-8', 17:31:05 INFO - 'LANGUAGE': 'en_US:en', 17:31:05 INFO - 'LOGNAME': 'cltbld', 17:31:05 INFO - 'MAIL': '/var/mail/cltbld', 17:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:05 INFO - 'MOZ_NO_REMOTE': '1', 17:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:05 INFO - 'NO_EM_RESTART': '1', 17:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:05 INFO - 'PWD': '/builds/slave/test', 17:31:05 INFO - 'SHELL': '/bin/bash', 17:31:05 INFO - 'SHLVL': '1', 17:31:05 INFO - 'TERM': 'linux', 17:31:05 INFO - 'TMOUT': '86400', 17:31:05 INFO - 'USER': 'cltbld', 17:31:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:05 INFO - '_': '/tools/buildbot/bin/python'} 17:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:31:05 INFO - Cleaning up... 17:31:05 INFO - Return code: 0 17:31:05 INFO - Running post-action listener: _resource_record_post_action 17:31:05 INFO - Running post-action listener: _start_resource_monitoring 17:31:05 INFO - Starting resource monitoring. 17:31:05 INFO - [mozharness: 2016-12-31 01:31:05.902085Z] Finished create-virtualenv step (success) 17:31:05 INFO - [mozharness: 2016-12-31 01:31:05.902592Z] Running install step. 17:31:05 INFO - Running pre-action listener: _resource_record_pre_action 17:31:05 INFO - Running main action method: install 17:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:06 INFO - Reading from file tmpfile_stdout 17:31:06 INFO - Detecting whether we're running mozinstall >=1.0... 17:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:31:06 INFO - Reading from file tmpfile_stdout 17:31:06 INFO - Output received: 17:31:06 INFO - Usage: mozinstall [options] installer 17:31:06 INFO - Options: 17:31:06 INFO - -h, --help show this help message and exit 17:31:06 INFO - -d DEST, --destination=DEST 17:31:06 INFO - Directory to install application into. [default: 17:31:06 INFO - "/builds/slave/test"] 17:31:06 INFO - --app=APP Application being installed. [default: firefox] 17:31:06 INFO - mkdir: /builds/slave/test/build/application 17:31:06 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'] 17:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:31:22 INFO - Reading from file tmpfile_stdout 17:31:22 INFO - Output received: 17:31:22 INFO - /builds/slave/test/build/application/firefox/firefox 17:31:22 INFO - Running post-action listener: _resource_record_post_action 17:31:22 INFO - [mozharness: 2016-12-31 01:31:22.274336Z] Finished install step (success) 17:31:22 INFO - [mozharness: 2016-12-31 01:31:22.274608Z] Running run-tests step. 17:31:22 INFO - Running pre-action listener: _resource_record_pre_action 17:31:22 INFO - Running main action method: run_tests 17:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:31:22 INFO - Python 2.7.3 17:31:22 INFO - Return code: 0 17:31:22 INFO - grabbing minidump binary from tooltool 17:31:22 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')]} 17:31:22 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 0x1a20820>, '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 0x1a21520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a219b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:31:22 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 17:31:22 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 17:31:22 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 17:31:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:31:22 INFO - Return code: 0 17:31:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:31:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:31:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:31:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:31:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:31:22 INFO - Using env: {'DISPLAY': ':0', 17:31:22 INFO - 'HOME': '/home/cltbld', 17:31:22 INFO - 'LANG': 'en_US.UTF-8', 17:31:22 INFO - 'LANGUAGE': 'en_US:en', 17:31:22 INFO - 'LOGNAME': 'cltbld', 17:31:22 INFO - 'MAIL': '/var/mail/cltbld', 17:31:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:22 INFO - 'MOZ_NO_REMOTE': '1', 17:31:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:22 INFO - 'NO_EM_RESTART': '1', 17:31:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:22 INFO - 'PWD': '/builds/slave/test', 17:31:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:22 INFO - 'SHELL': '/bin/bash', 17:31:22 INFO - 'SHLVL': '1', 17:31:22 INFO - 'TERM': 'linux', 17:31:22 INFO - 'TMOUT': '86400', 17:31:22 INFO - 'USER': 'cltbld', 17:31:22 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691', 17:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:22 INFO - '_': '/tools/buildbot/bin/python'} 17:31:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:31:22 INFO - mozversion application_buildid: 20161231005610 17:31:22 INFO - mozversion application_changeset: 81463aec62d02fa1915e03cda7a8ddc8d44c71fc 17:31:22 INFO - mozversion application_display_name: Nightly 17:31:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:31:22 INFO - mozversion application_name: Firefox 17:31:22 INFO - mozversion application_remotingname: firefox 17:31:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:31:22 INFO - mozversion application_vendor: Mozilla 17:31:22 INFO - mozversion application_version: 53.0a1 17:31:22 INFO - mozversion platform_buildid: 20161231005610 17:31:22 INFO - mozversion platform_changeset: 81463aec62d02fa1915e03cda7a8ddc8d44c71fc 17:31:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:31:22 INFO - mozversion platform_version: 53.0a1 17:31:22 INFO - using testdate: 1483147882 17:31:22 INFO - actual date: 1483147882 17:31:22 INFO - starting webserver on 'localhost:50477' 17:31:22 INFO - SUITE-START | Running 2 tests 17:31:22 INFO - TEST-START | dromaeo_css 17:31:22 INFO - operating with platform_type : linux_ 17:31:22 INFO - Initialising browser for dromaeo_css test... 17:31:22 INFO - TEST-INFO | started process 7432 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzDzdB2/profile http://localhost:50477/getInfo.html) 17:31:23 INFO - PROCESS | 7432 | 1483147883077 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:31:27 INFO - PROCESS | 7432 | __metrics Screen width/height:1600/1200 17:31:27 INFO - PROCESS | 7432 | colorDepth:24 17:31:27 INFO - PROCESS | 7432 | Browser inner width/height: 1024/697 17:31:27 INFO - PROCESS | 7432 | __metrics 17:31:27 ERROR - PROCESS | 7432 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 17:31:28 INFO - TEST-INFO | 7432: exit 0 17:31:28 INFO - Browser initialized. 17:31:28 INFO - Running cycle 1/1 for dromaeo_css test... 17:31:28 INFO - TEST-INFO | started process 7551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzDzdB2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:31:40 INFO - PROCESS | 7551 | RSS: Main: 158703616 17:31:40 INFO - PROCESS | 7551 | 17:32:30 INFO - PROCESS | 7551 | 1483147950319 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] 17:34:44 INFO - PROCESS | 7551 | Cycle 1(1): loaded http://localhost:50477/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50477/tests/dromaeo/cssquery-ext.html) 17:34:45 INFO - PROCESS | 7551 | RSS: Main: 164700160 17:34:45 INFO - PROCESS | 7551 | 17:36:29 INFO - PROCESS | 7551 | ************************* 17:36:29 INFO - PROCESS | 7551 | A coding exception was thrown and uncaught in a Task. 17:36:29 INFO - PROCESS | 7551 | 17:36:29 INFO - PROCESS | 7551 | Full message: ReferenceError: fetch is not defined 17:36:29 INFO - PROCESS | 7551 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 17:36:29 INFO - PROCESS | 7551 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 17:36:29 INFO - PROCESS | 7551 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 17:36:29 INFO - PROCESS | 7551 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:36:29 INFO - PROCESS | 7551 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:36:29 INFO - PROCESS | 7551 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:36:29 INFO - PROCESS | 7551 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 17:36:29 INFO - PROCESS | 7551 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:36:29 INFO - PROCESS | 7551 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:36:29 INFO - PROCESS | 7551 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:36:29 INFO - PROCESS | 7551 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 17:36:29 INFO - PROCESS | 7551 | 17:36:29 INFO - PROCESS | 7551 | ************************* 17:37:48 INFO - PROCESS | 7551 | Cycle 1(1): loaded http://localhost:50477/tests/dromaeo/cssquery-ext.html (next: http://localhost:50477/tests/dromaeo/cssquery-jquery.html) 17:37:48 INFO - PROCESS | 7551 | RSS: Main: 163057664 17:37:48 INFO - PROCESS | 7551 | 17:40:51 INFO - PROCESS | 7551 | Cycle 1(1): loaded http://localhost:50477/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50477/tests/dromaeo/cssquery-mootools.html) 17:40:51 INFO - PROCESS | 7551 | RSS: Main: 168558592 17:40:51 INFO - PROCESS | 7551 | 17:43:53 INFO - PROCESS | 7551 | Cycle 1(1): loaded http://localhost:50477/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50477/tests/dromaeo/cssquery-prototype.html) 17:43:54 INFO - PROCESS | 7551 | RSS: Main: 162934784 17:43:54 INFO - PROCESS | 7551 | 17:46:57 INFO - PROCESS | 7551 | Cycle 1(1): loaded http://localhost:50477/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50477/tests/dromaeo/cssquery-yui.html) 17:46:58 INFO - PROCESS | 7551 | RSS: Main: 165871616 17:46:58 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | Cycle 1(1): loaded http://localhost:50477/tests/dromaeo/cssquery-yui.html (next: http://localhost:50477/tests/dromaeo/cssquery-dojo.html) 17:50:01 INFO - PROCESS | 7551 | RSS: Main: 166137856 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | __start_tp_report 17:50:01 INFO - PROCESS | 7551 | _x_x_mozilla_page_load 17:50:01 INFO - PROCESS | 7551 | _x_x_mozilla_page_load_details 17:50:01 INFO - PROCESS | 7551 | |i|pagename|runs| 17:50:01 INFO - PROCESS | 7551 | |0;dojo.html;2546.287039628904;2604.3879688273255;2614.856182909939;2628.369191394066;2641.418887844675;4675.859724208271;4792.041591681665;4803.135435621591;4809.591184749301;4812.5909297709695;4694.812207511704;4784.234522476405;4790.904181916359;4818.27725841124;4836.129496690594;4361.105973275471;4392.538783427742;4397.890052748675;4463.040446304044;4506.098780243948;4378.321360189176;4383.364412160235;4464.575865292792;4480.955190448087;4486.506484286726;4273.252180868367;4282.357646353041;4283.271843786564;4451.310046942729;4463.776811159429;4328.27768119417;4339.218940590692;4361.781910904442;4362.934555981631;4363.650907927358;4302.010537576343;4315.978420107911;4377.5403582624;4410.647148228134;4429.579189976947;4682.906341873178;4712.246040633517;4724.433068031858;4732.526747325274;4827.25177597473;5099.056674515226;5113.718745468997;5117.283580298761;5120.795168193268;5178.326817513736;5017.49825017499;5059.392872855281;5111.335526381584;5126.077306084903;5160.354955630546;4826.227803551415;4833;4838.516148385133;4852.636052296092;4855.441624213226;5269.657472264291;5285.9735701322015;5295.073362161606;5311.893762124736;5317.8404647860625;4860.198067318853;4896.436909755353;4907.337509436252;4911.631627627942;5032.119379108642;4928.778204980713;5019.322391477104;5086.949130508721;5098.796048158107;5105.974470127626;4941.03649788295;5002.1496345621335;5031.547160755549;5056.469070747592;5071.923921141186;5047.16721740916;5059.848204553869;5068.493150684902;5071.239314102914;5117.5394214520875;4301.720388174821;4341.240282950471;4342.652587792969;4343.67422443318;4390.429244198233;3696.4270538066644;3710.165212827092;3727.5713292971654;3808.8286027128847;3825.885223443301;4439.911201775946;4460.420145381079;4461.263891457873;4489.326601009875;4491.528389519119;5131.820386286462;5147.356580427447;5156.381234251914;5160.045391602565;5212.843614691566;4345.326474396474;4390.62679672225;4395;4418.558144185556;4445.533219012022;4604.746738929391;4608.585227329557;4630.259158534618;4644.187267228222;4647.883802904955;4770.522947705271;4779.665423420327;4813.614910807057;4820.638452116105;4859.829905953275;5021.799128034838;5068.493150684902;5091.923621145612;5147.974260128675;5148.382194136728;4755.881102972453;4788.209945358977;4791.281307803857;4800.399950006249;4845.903081938412;5012.223105418666;5014.473480284518;5033.968036552425;5087.829799146143;5091.389033316026;4933.531314525115;5074.720890350918;5154.561862241601;5156.20078887773;5213.81751638706;4985.277134815359;4987.675801072919;4990.72551009698;4999.825006124768;5002.349694539686;4954.9752251238515;4956.430010548689;4956.529129732669;4957.950420495749;4961.925571116509;4682.274247491644;4913.828016019565;4914.36113305268;4917.164082105979;4956.083124621957;4983.526564976321;5042.067217564762;5052;5069.645124841226;5121.539061484485;4818.638602104786;4887.975560122177;4926.753662316942;4941.061198372297;4985.252212168204 17:50:01 INFO - PROCESS | 7551 | |1;ext.html;513.5634710496078;523.1211564571519;530.0194639916153;534.5082524077847;534.8956953394089;36030.459543106845;37971;38081.42877856834;38101.428478572845;41391;38735.61264387355;39211.41182882252;39318.410223846666;39340.40989385162;39397;7580.431467639935;7730.574818384986;7737.613119344011;9408.15326620604;9708.320417570772;9603.80792384152;9704;9704.854427183598;9714.174295184917;9714.465704386292;9644.807103857918;9698.563564639573;9713.32006759527;9717.757056073619;9717.90282097177;9603.90396096044;9641.373310734814;9700.223982081416;9700.563474643657;9707.320487565908;9414.905850941472;9509.144177024033;9521.238300935978;9525.57134928923;9530.142287194185;32517;32846;32954.50568241478;32955.50566741501;32982.835085824416;3448.5516882805327;3475.6350583188655;3500.3524347995703;3512.3150985557827;3527.770694904849;25007.70033285317;25204.495910081878;25564.616530752053;25582.616260755916;25715.22854314355;3360.462326027848;3401.3470090231617;3413.351463222005;3464.064702530328;3467.9479807802904;224266;228465;230222;230953.9999999983;231336;44504.77747611241;44659.553404465536;44750;44802;44839.327410088874;46121.308180377324;46217.30674039892;46421.535784641725;46433.767831161305;46475;10902.345859248311;11280.492377843017;11335.489902954387;11337.319760814378;11363.943180284046;7955.562444065632;8174.692049272088;8185.140560241208;8381.49711017341;8400.747977560683;15239.390424383122;15257.771133433007;15289.235538223043;15386.615334616725;15487.760979121644;6539.411452969255;6628.23775265841;6661.600303981777;6747.763828265987;6761.966190169018;785.3089281432303;787.5865170785269;788.66087582339;796.4663675337538;810.878368244768;25940.481190376086;26985.595216071775;27490;27560;27661.30846728848;909.3043821476575;922.7047344849718;933.2254228989922;938.779386844091;1077.6012875236213;14189.716205676035;14358.641033974025;14389.78415323771;14534;14590;344907;345245;345892;346967;347998;47149;47846;47907.9999999993;47974.5202547977;48023;1135.2223726747204;1177.8763229860913;1192.0880526397314;1199.196538319328;1206.6259459567561;25252.494950101263;25767.742322576534;26153.869230654487;26642.20073397801;26727.86636066807;38380.2323953525;38975.415368768925;39399.21201575895;39508.802455987534;39552.66039084687;297197;298153;298642;298750;300103;22268.554628907656;23062.538749225256;23067.307980760605;24260.75739242585;24497.26508204757;34639.653603463645;35135.64864351324;35381.64618353886;35777.82111089428;36261.81869090637;29935;30031.54952675668;30261.697383026767;30864.845675771478;31113.53329700011;30075.69924300729;30408.239794004443;30715.539266911463;30901.8454907724;31798.364032719677;30416.847915761165;30473.238169045064;30514.694853051184;30543.694563054087;30633.54049689301;26499.735002650497;27846.58230126509;27957.860210698815;28183.859080704464;28474.145775626763;21778.56442871102;21930;22024.229151979605;22235.99938002826;22243.888780555993 17:50:01 INFO - PROCESS | 7551 | |2;jquery.html;366.6315353070164;368.67556550235787;375.6281281531284;391.06730447881796;395.3555704202152;15335.38658453662;15890.76163857543;16386;16405.589860253483;16415.17924103796;15498.380064797422;16281.918590407062;16313.673726525461;16497;16597.253123609436;4845.394325709286;5112.795488180468;5120.283160357534;5120.436751957282;5143.588512918957;5044.571211447032;5173.275741396207;5365.624406291561;5409.56723462122;5532.529734972532;5476.288082549263;5502.477264659851;5515.172724091399;5521.834344969638;5531;5355.758990845421;5502.669839809624;5519.31008623922;5526.889462210774;5544.944550554484;5268.130758424855;5343.973280133613;5345.4654534546235;5348.705821179834;5351.357837059578;19133.521661958424;19380.4185874424;19567.902160489248;19773.802261977486;19788.70316945247;2038.1337931379105;2043.8160565549024;2133.5839511295303;2157.1802714968367;2185.715856938604;8653.307735381155;9148.679796207101;9221.953890230572;9392.436453812794;9435.811283774356;1690.1211370087608;1767.3372485318005;1770.2918832466987;1772.884762490447;1787.6603445345343;60392.39607603912;60930.695346523426;62029.6898515509;62520.68739656318;64173.358266417206;9360.344775865624;9419.387739796892;9580.377275477073;9631.133198012212;10280.948595256976;10691.144708423462;11059.502322395512;11094.722631934104;13248.536301229411;13314.134581252187;5928.258967629046;6023;6058.12157237207;6216.595921265103;6267.62394256346;5719.857003574861;5759.568032397587;5872.588918775645;5925.911111333334;5938.584299099108;3559.4549896823896;3588.0671025533547;3627.1657518770303;3712.777233366007;3721.683656889141;1728.5764987577872;1758.824117588231;1761.1898526677614;1790.3823181002533;1835.2016872660436;950.0126569084423;954.5275088831073;969.7963427680126;976.3019441099648;1020.0819262663662;15418;15695;15731.370745170065;15831.525054248392;16266;950.1591091883732;951.0727041134976;954.9665761698308;956.4404823178519;980;8576.742697719079;8998.325125615605;9079.50062746542;9213.262938964866;9362;248320;250546;250858;295619;345016;67037.66481167563;67230.66384668143;68159.3184068163;69578;75782;900.1808354397467;918.3663272342196;963.0465838819518;966.9605174437507;967.9564419601135;14858.700100821701;14883.47907823221;14955.626109347353;14984.016850452237;15509.5347139586;28219;35218.64781352154;35662.465063024596;36136.8193159043;36953.815230924745;45227.32159017552;46218.53781462277;46284.305735414666;46458.53541464542;47375.28937066015;7224.313690199423;7289.5261807982315;7398.593077380796;7435.405167586472;7557.470977031555;8444.619992100494;10512.526936288039;11251.268667536584;11556.47995840206;11658.88341116612;9634.325597208128;10204.744881378025;10251.026152515498;10286.434246116536;10508.369497830156;9938.254630902857;9953.502324883872;10262.948685256526;10292.794144117226;10573.101286390744;9585.760355991157;10203.132733717717;10211.642592509226;10368.792624147323;10665.36007839532;6506.544541882023;6576.112224849584;6702.329767023259;7489.438292128221;7743.148253691977;9385.202257808163;9911;10003.299439095226;10017.198624109908;10047.095761381219 17:50:01 INFO - PROCESS | 7551 | |3;mootools.html;1274.9553765618205;1275.866034066423;1284.7880099783536;1285.665726911003;1301.6290357248186;6595.868082638344;6814.727410903558;6914.204866440361;7339.5963222022765;7374.041374621298;7021.543599666018;7198.4601154913325;7207.17117531484;7419.146798118218;7455.813604659873;2136.594047131048;2191.51786606947;2193.616117179495;2195.3743183192714;2206.2388475975845;2139.711138996239;2151.526664133888;2178.9564208715815;2186.092771499834;2200.207925146954;2113.936581902538;2177.4556360909774;2177.8148857347146;2181.312886440774;2181.7381914170323;1885.58517126232;1914.2343062774862;1928.7299778031088;1929.7008963610663;1934.6807776716826;2142.625040617901;2162.9783702162936;2193.6599827026835;2194.5391467791783;2197.187040794916;7379.225181355934;7410.184879663233;7418.703251869919;7435.814104647373;7438.516496427769;4484.484284307281;4571.971306456021;4581.060882519076;4593.104344652794;4603.470600880908;2735;2887.63904511936;2900.129961011688;2951.837648929308;2962.1113665900143;1143.091242462236;1159.113278342069;1168.2021558367662;1192.320377384891;1225.3995542184355;9623.230141588656;9721.562529686182;9766.951165244129;9779.608815647365;9841.704748857546;4067.9389809152885;4119.876403707894;4144.79276036197;4171.478565179353;4266.1254442839145;8135.349172066339;8142.715004974797;8146.266835984671;8157.1842815717955;8170;4532.9773351133035;4544.727316361029;4548.772561371984;4561.452625684939;4564.908701825945;4081.53064896637;4152.1072969311745;4173.624373806311;4177.498700156001;4187.309093999456;3513.4202856528386;3583.569971603424;3621.402468592705;3715.6655900968503;3717.386631205821;1129.4804389980536;1161.0479406886272;1173.196360493065;1180.1738782852037;1235.7960936445386;645.2869579115104;663.2869665110026;674.6087269383746;815.3517953227199;829.8796674482167;4623.352730617717;4760.214564596872;4816.638752093607;4870.853874383774;4957.082939656175;842.2588122452203;843.2200214801285;843.5529169540154;847.8304339132198;871.5598622695625;3205.487122060459;3299.5875515560556;3347.866085356607;3371.763976521669;3382.2389962258294;8469.153084691605;8689.652413903374;8762;8773.210411063035;8778.166074222938;8746.169113934166;8808.471491710518;8814.559272036373;8827.24968377695;8833;6761.3238676132;6807.65961701913;6870.27862074485;6935.133108361354;6947.228456743498;4686.203345431353;4693.202155633482;4729.929051064168;4763.380760501252;4842.443119041214;7967.601619918865;8003.719869804529;8034.357251419756;8087.150849160754;8098.838023239385;7723.227677232232;8007.559584222924;8012.038555373392;8104.662730649613;8127.593620318842;6330.082138089859;6444.2911279758255;6452.709628066655;6453.548251622341;6456.870862582816;6953.930460695328;6957.2694867038235;6991.8113920632595;7000.929990700231;7003.824904377431;6348.396902294275;6426.807195784134;6477.870442591027;6527.934720652733;6552.213734351908;6327.11420401135;6346.270178929298;6374.4900407966325;6376.023432735867;6557.2131344239;5705.115707065412;5718.656880587178;5748.338941021836;5754.6547207167705;5761.798337058183;4171.311733563928;4181.770002649883;4257.212415703105;4265.808038638331;4346.543612920598;4217.409562661169;4249.447571815645;4253.149370126049;4301.612854843079;4407.118576284692 17:50:01 INFO - PROCESS | 7551 | |4;prototype.html;337.7163182926341;355.6141586378779;362.0839276630127;364.1497104261549;364.1715098151706;4906.337644418002;4941.9199608639865;4993.001399720057;5016.673916195449;5043.420006699231;4823.927641085386;4896.583790377822;4957.380327459067;4960.355153829997;4970.950290497094;3958.4656071430422;3960.9603903961024;3973.3046716824583;3990.64084232419;4016.7389119707273;3902;4006.3589825627905;4012.879613611596;4016.5581786003518;4016.7021287222683;3940.901477463072;3964.6431821136093;3982.9203415931665;3983.780892050936;3986.4851356484496;3947.4538922395036;3957.6635985941084;3958.9208215835524;3969.384745364473;3977.9657289104753;3578.4095624221973;3591;3597.2086141048926;3608.8015159166234;3624.4925710400566;4669.813207471697;4841.298011788306;4871.805127794884;4886.828960986384;4902.901941961177;4430.8449204277695;4479.664025198123;4491.562699936022;4508.977455112704;4520.728756274601;5174.275601415805;5189.351331083614;5191.454897235812;5199.428062913114;5218.530332270076;3778.9433158502643;3890.980545097285;3930.3885406983286;3933.8426462941447;3944.447777311179;5387.73061346935;5398.921415255116;5408.296386638236;5440.857419941817;5484.561235101182;3969.8809035728973;3975.582563830814;3983.900402489961;3997.980010099931;4006.7996600169877;4104.548499665035;4109.938350924738;4113.814878330482;4116.382542618631;4123.195976784558;4091.8977025574004;4261.808218630169;4291.763952982616;4316.697831151852;4317.503487098961;5176.896462070813;5186.766595503212;5196.53231209193;5203.947960520422;5220.791168353299;1218.5065048655308;1254.5483625894672;1263.810428435742;1265.911386202957;1273.9171953822972;866.7486428935664;876.3305902256212;879.2394578689388;879.463527248378;881.5239770523973;4400.623663221888;4416.138852923649;4419.160359531678;4426.18115648606;4430;5154.427899490616;5158.613104017214;5196.480351964773;5212.478752124757;5220.381681678692;4342.41377414045;4530.365748795171;4592.977035114803;4594.954050459452;4605.424321959755;2756.476269508787;2761.4891245119716;2764.350377661248;2785.247983044567;2786.3452088759127;4747.715137091785;4795.20879054952;4840;4842.6368022398465;4850.975745121252;5263.973680131651;5275.57795376369;5324.9733751331;5340.739371718854;5346.8128615499045;4857.7085374876915;4870.610351171968;4874.7562621868765;4881.755912204376;4894.216925291937;5043.773030213576;5128.820491282787;5196.766145523537;5197.064528384927;5232.6395137265345;5125.589952803842;5126.307948426915;5142.5885929126325;5152.149895267239;5154.974225128851;4838.830640927691;4853.466118726867;4864.927026094682;4903.485134060872;4931.72875491837;3844.9807750961068;3883.728139030241;3929.7011248419994;3944.6055394460323;3984.442178095127;5159.277701121771;5168.328117344721;5172.456892026283;5196.922046169386;5214.322138122172;5031.421386540448;5043.873903152304;5074.270411247804;5074.467180945948;5101.69389836611;4818.397700287464;4971.502849715144;5007.273945277841;5044.4451110377095;5050.292958985671;4949.57928576075;4955.82654607087;4977.726225057657;5093.185090385668;5127.92308115386;5216.843494695166;5220.295260139833;5224.425313215619;5227.712475813714;5277.234800953764;5151.871203219949;5160.303359046481;5160.741962901915;5167.534921856901;5170.870728231825 17:50:01 INFO - PROCESS | 7551 | |5;yui.html;646.689588997281;660.2341284110432;725.231254869838;745.4409193105171;761.5849362097656;12067.336296503689;12996.54512092077;14421.49524766632;14963.102213867176;14979;15235.542933711979;15280.770788438182;15308.081515109101;15431.845681543182;15512.379504819823;664.9601023938579;810.5541951926432;839.9664013439456;841.4236248170023;843.4770442325736;665.5241502325836;793.3811626931004;808.9393295502831;825.4428260923883;825.5129473610568;865.485036403339;866.5797088412147;867.1112110087148;867.6312567158964;870.6212797433137;855.0978717453085;857.1557016338901;860.7632900952226;861.0958493581714;861.2335021830576;630.5649102119569;634.0647544871277;634.815903388017;635.5995722694719;635.7806556737918;13117.737645247042;13201.537946171838;13210.801837972438;13299;14641.633959151;12980.15628984113;13502.257375844423;13558.389872455664;13614.93192534031;13624.386902589407;10509.905813575806;10648.840267395995;10810;10864.511097000654;10921.945390272998;3189.346184032268;3223.2908760072746;3247.1882029492626;3247.8376081196084;3285.737141028712;64576.35423645798;64908.67545662241;65351.673241633966;65504.3449565503;66355.3364466354;9767.951160244153;9866.457344846101;9889.653862114936;10108.916780381907;10160;18965.810341896406;19010.71483927742;19090.713639295423;19113.235470581298;19152.61694766125;6389.169407976933;6742.393184613408;6841.486888483384;6877.3810357068105;6921.1002569666625;3488.180277634754;3597.568291805;3598.7480876338927;3627.147620309225;3657.817109144532;2893.23329317731;2959.5708622249654;2971.9108426746798;2976.241058530061;2976.866041028159;474.8931490414629;489.0732062741086;489.85059556835506;493.7481884238992;502.25164504887454;508.4204007431531;539.6589475154238;542.4900593442152;546.0880329849161;548.7475761149836;8035.558044307502;8176.509409435453;8386.371022173362;8477.788055298666;8570.871436928452;566.2242727463401;593.1310629927177;596.5496050481945;602.8643555200133;604.241676695744;3951.4467974483596;4193.454850869368;4261.91476170481;4279.101388708344;4344.587264209895;50493;51280;52708;53737;68608;56929;57309;58695.706521467975;59768.10347844786;59778;52453.475465245625;53110.20334694983;53172.73413632907;53267.46732532625;53477;7981.3614910808155;8162.142974987541;8211.917880821115;8224.259816616533;8348.248657620843;36816.63183368132;37034;37202.8139859299;37732.43401349037;38310;45459.54540454553;47185.52814471949;47284.7635761819;47388.289175663085;49011.264831028275;9512.33413661037;9919.65281215154;10023.448710321003;11626.302421854716;11887.16789824704;12573.434195461408;13233.735325293634;13259.403326850508;13297.80053299182;13946.721065578835;12106.75786484283;12227.388630568614;12282.01743860507;12777.297248651414;13347;11213.943930280297;11436.485358159069;11457.427128643702;11666.650000499998;13069.019823513085;11173.553057877594;11183.384913829492;11900.04799616046;12066.040880080673;12120.93939530332;9505.952470237604;10104.494775261355;10374.481275936023;10478.63324783629;10577.259591828499;9825.4104753715;10102.39385636864;10150.238732094975;10344.689659310232;10416.687499375032 17:50:01 INFO - PROCESS | 7551 | __end_tp_report 17:50:01 INFO - PROCESS | 7551 | __start_cc_report 17:50:01 INFO - PROCESS | 7551 | _x_x_mozilla_cycle_collect,327 17:50:01 INFO - PROCESS | 7551 | __end_cc_report 17:50:01 INFO - PROCESS | 7551 | __startTimestamp1483149001417__endTimestamp 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | ------- Summary: start ------- 17:50:01 INFO - PROCESS | 7551 | Number of tests: 6 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | [#0] dojo.html Cycles:165 Average:4717.03 Median:4838.52 stddev:502.60 (10.4%) stddev-sans-first:474.42 17:50:01 INFO - PROCESS | 7551 | Values: 2546.3 2604.4 2614.9 2628.4 2641.4 4675.9 4792.0 4803.1 4809.6 4812.6 4694.8 4784.2 4790.9 4818.3 4836.1 4361.1 4392.5 4397.9 4463.0 4506.1 4378.3 4383.4 4464.6 4481.0 4486.5 4273.3 4282.4 4283.3 4451.3 4463.8 4328.3 4339.2 4361.8 4362.9 4363.7 4302.0 4316.0 4377.5 4410.6 4429.6 4682.9 4712.2 4724.4 4732.5 4827.3 5099.1 5113.7 5117.3 5120.8 5178.3 5017.5 5059.4 5111.3 5126.1 5160.4 4826.2 4833.0 4838.5 4852.6 4855.4 5269.7 5286.0 5295.1 5311.9 5317.8 4860.2 4896.4 4907.3 4911.6 5032.1 4928.8 5019.3 5086.9 5098.8 5106.0 4941.0 5002.1 5031.5 5056.5 5071.9 5047.2 5059.8 5068.5 5071.2 5117.5 4301.7 4341.2 4342.7 4343.7 4390.4 3696.4 3710.2 3727.6 3808.8 3825.9 4439.9 4460.4 4461.3 4489.3 4491.5 5131.8 5147.4 5156.4 5160.0 5212.8 4345.3 4390.6 4395.0 4418.6 4445.5 4604.7 4608.6 4630.3 4644.2 4647.9 4770.5 4779.7 4813.6 4820.6 4859.8 5021.8 5068.5 5091.9 5148.0 5148.4 4755.9 4788.2 4791.3 4800.4 4845.9 5012.2 5014.5 5034.0 5087.8 5091.4 4933.5 5074.7 5154.6 5156.2 5213.8 4985.3 4987.7 4990.7 4999.8 5002.3 4955.0 4956.4 4956.5 4958.0 4961.9 4682.3 4913.8 4914.4 4917.2 4956.1 4983.5 5042.1 5052.0 5069.6 5121.5 4818.6 4888.0 4926.8 4941.1 4985.3 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | [#1] ext.html Cycles:180 Average:43180.81 Median:175217.47 stddev:77582.77 (44.3%) stddev-sans-first:77734.27 17:50:01 INFO - PROCESS | 7551 | Values: 513.6 523.1 530.0 534.5 534.9 36030.5 37971.0 38081.4 38101.4 41391.0 38735.6 39211.4 39318.4 39340.4 39397.0 7580.4 7730.6 7737.6 9408.2 9708.3 9603.8 9704.0 9704.9 9714.2 9714.5 9644.8 9698.6 9713.3 9717.8 9717.9 9603.9 9641.4 9700.2 9700.6 9707.3 9414.9 9509.1 9521.2 9525.6 9530.1 32517.0 32846.0 32954.5 32955.5 32982.8 3448.6 3475.6 3500.4 3512.3 3527.8 25007.7 25204.5 25564.6 25582.6 25715.2 3360.5 3401.3 3413.4 3464.1 3467.9 224266.0 228465.0 230222.0 230954.0 231336.0 44504.8 44659.6 44750.0 44802.0 44839.3 46121.3 46217.3 46421.5 46433.8 46475.0 10902.3 11280.5 11335.5 11337.3 11363.9 7955.6 8174.7 8185.1 8381.5 8400.7 15239.4 15257.8 15289.2 15386.6 15487.8 6539.4 6628.2 6661.6 6747.8 6762.0 785.3 787.6 788.7 796.5 810.9 25940.5 26985.6 27490.0 27560.0 27661.3 909.3 922.7 933.2 938.8 1077.6 14189.7 14358.6 14389.8 14534.0 14590.0 344907.0 345245.0 345892.0 346967.0 347998.0 47149.0 47846.0 47908.0 47974.5 48023.0 1135.2 1177.9 1192.1 1199.2 1206.6 25252.5 25767.7 26153.9 26642.2 26727.9 38380.2 38975.4 39399.2 39508.8 39552.7 297197.0 298153.0 298642.0 298750.0 300103.0 22268.6 23062.5 23067.3 24260.8 24497.3 34639.7 35135.6 35381.6 35777.8 36261.8 29935.0 30031.5 30261.7 30864.8 31113.5 30075.7 30408.2 30715.5 30901.8 31798.4 30416.8 30473.2 30514.7 30543.7 30633.5 26499.7 27846.6 27957.9 28183.9 28474.1 21778.6 21930.0 22024.2 22236.0 22243.9 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | [#2] jquery.html Cycles:180 Average:20265.00 Median:4944.98 stddev:46875.30 (947.9%) stddev-sans-first:46982.98 17:50:01 INFO - PROCESS | 7551 | Values: 366.6 368.7 375.6 391.1 395.4 15335.4 15890.8 16386.0 16405.6 16415.2 15498.4 16281.9 16313.7 16497.0 16597.3 4845.4 5112.8 5120.3 5120.4 5143.6 5044.6 5173.3 5365.6 5409.6 5532.5 5476.3 5502.5 5515.2 5521.8 5531.0 5355.8 5502.7 5519.3 5526.9 5544.9 5268.1 5344.0 5345.5 5348.7 5351.4 19133.5 19380.4 19567.9 19773.8 19788.7 2038.1 2043.8 2133.6 2157.2 2185.7 8653.3 9148.7 9222.0 9392.4 9435.8 1690.1 1767.3 1770.3 1772.9 1787.7 60392.4 60930.7 62029.7 62520.7 64173.4 9360.3 9419.4 9580.4 9631.1 10280.9 10691.1 11059.5 11094.7 13248.5 13314.1 5928.3 6023.0 6058.1 6216.6 6267.6 5719.9 5759.6 5872.6 5925.9 5938.6 3559.5 3588.1 3627.2 3712.8 3721.7 1728.6 1758.8 1761.2 1790.4 1835.2 950.0 954.5 969.8 976.3 1020.1 15418.0 15695.0 15731.4 15831.5 16266.0 950.2 951.1 955.0 956.4 980.0 8576.7 8998.3 9079.5 9213.3 9362.0 248320.0 250546.0 250858.0 295619.0 345016.0 67037.7 67230.7 68159.3 69578.0 75782.0 900.2 918.4 963.0 967.0 968.0 14858.7 14883.5 14955.6 14984.0 15509.5 28219.0 35218.6 35662.5 36136.8 36953.8 45227.3 46218.5 46284.3 46458.5 47375.3 7224.3 7289.5 7398.6 7435.4 7557.5 8444.6 10512.5 11251.3 11556.5 11658.9 9634.3 10204.7 10251.0 10286.4 10508.4 9938.3 9953.5 10262.9 10292.8 10573.1 9585.8 10203.1 10211.6 10368.8 10665.4 6506.5 6576.1 6702.3 7489.4 7743.1 9385.2 9911.0 10003.3 10017.2 10047.1 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | [#3] mootools.html Cycles:180 Average:4810.16 Median:4790.01 stddev:2583.30 (53.9%) stddev-sans-first:2576.88 17:50:01 INFO - PROCESS | 7551 | Values: 1275.0 1275.9 1284.8 1285.7 1301.6 6595.9 6814.7 6914.2 7339.6 7374.0 7021.5 7198.5 7207.2 7419.1 7455.8 2136.6 2191.5 2193.6 2195.4 2206.2 2139.7 2151.5 2179.0 2186.1 2200.2 2113.9 2177.5 2177.8 2181.3 2181.7 1885.6 1914.2 1928.7 1929.7 1934.7 2142.6 2163.0 2193.7 2194.5 2197.2 7379.2 7410.2 7418.7 7435.8 7438.5 4484.5 4572.0 4581.1 4593.1 4603.5 2735.0 2887.6 2900.1 2951.8 2962.1 1143.1 1159.1 1168.2 1192.3 1225.4 9623.2 9721.6 9767.0 9779.6 9841.7 4067.9 4119.9 4144.8 4171.5 4266.1 8135.3 8142.7 8146.3 8157.2 8170.0 4533.0 4544.7 4548.8 4561.5 4564.9 4081.5 4152.1 4173.6 4177.5 4187.3 3513.4 3583.6 3621.4 3715.7 3717.4 1129.5 1161.0 1173.2 1180.2 1235.8 645.3 663.3 674.6 815.4 829.9 4623.4 4760.2 4816.6 4870.9 4957.1 842.3 843.2 843.6 847.8 871.6 3205.5 3299.6 3347.9 3371.8 3382.2 8469.2 8689.7 8762.0 8773.2 8778.2 8746.2 8808.5 8814.6 8827.2 8833.0 6761.3 6807.7 6870.3 6935.1 6947.2 4686.2 4693.2 4729.9 4763.4 4842.4 7967.6 8003.7 8034.4 8087.2 8098.8 7723.2 8007.6 8012.0 8104.7 8127.6 6330.1 6444.3 6452.7 6453.5 6456.9 6953.9 6957.3 6991.8 7000.9 7003.8 6348.4 6426.8 6477.9 6527.9 6552.2 6327.1 6346.3 6374.5 6376.0 6557.2 5705.1 5718.7 5748.3 5754.7 5761.8 4171.3 4181.8 4257.2 4265.8 4346.5 4217.4 4249.4 4253.1 4301.6 4407.1 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | [#4] prototype.html Cycles:180 Average:4303.75 Median:4855.59 stddev:1211.55 (25.0%) stddev-sans-first:1177.81 17:50:01 INFO - PROCESS | 7551 | Values: 337.7 355.6 362.1 364.1 364.2 4906.3 4941.9 4993.0 5016.7 5043.4 4823.9 4896.6 4957.4 4960.4 4971.0 3958.5 3961.0 3973.3 3990.6 4016.7 3902.0 4006.4 4012.9 4016.6 4016.7 3940.9 3964.6 3982.9 3983.8 3986.5 3947.5 3957.7 3958.9 3969.4 3978.0 3578.4 3591.0 3597.2 3608.8 3624.5 4669.8 4841.3 4871.8 4886.8 4902.9 4430.8 4479.7 4491.6 4509.0 4520.7 5174.3 5189.4 5191.5 5199.4 5218.5 3778.9 3891.0 3930.4 3933.8 3944.4 5387.7 5398.9 5408.3 5440.9 5484.6 3969.9 3975.6 3983.9 3998.0 4006.8 4104.5 4109.9 4113.8 4116.4 4123.2 4091.9 4261.8 4291.8 4316.7 4317.5 5176.9 5186.8 5196.5 5203.9 5220.8 1218.5 1254.5 1263.8 1265.9 1273.9 866.7 876.3 879.2 879.5 881.5 4400.6 4416.1 4419.2 4426.2 4430.0 5154.4 5158.6 5196.5 5212.5 5220.4 4342.4 4530.4 4593.0 4595.0 4605.4 2756.5 2761.5 2764.4 2785.2 2786.3 4747.7 4795.2 4840.0 4842.6 4851.0 5264.0 5275.6 5325.0 5340.7 5346.8 4857.7 4870.6 4874.8 4881.8 4894.2 5043.8 5128.8 5196.8 5197.1 5232.6 5125.6 5126.3 5142.6 5152.1 5155.0 4838.8 4853.5 4864.9 4903.5 4931.7 3845.0 3883.7 3929.7 3944.6 3984.4 5159.3 5168.3 5172.5 5196.9 5214.3 5031.4 5043.9 5074.3 5074.5 5101.7 4818.4 4971.5 5007.3 5044.4 5050.3 4949.6 4955.8 4977.7 5093.2 5127.9 5216.8 5220.3 5224.4 5227.7 5277.2 5151.9 5160.3 5160.7 5167.5 5170.9 17:50:01 INFO - PROCESS | 7551 | 17:50:01 INFO - PROCESS | 7551 | [#5] yui.html Cycles:180 Average:14908.90 Median:47336.53 stddev:18151.41 (38.3%) stddev-sans-first:18170.73 17:50:01 INFO - PROCESS | 7551 | Values: 646.7 660.2 725.2 745.4 761.6 12067.3 12996.5 14421.5 14963.1 14979.0 15235.5 15280.8 15308.1 15431.8 15512.4 665.0 810.6 840.0 841.4 843.5 665.5 793.4 808.9 825.4 825.5 865.5 866.6 867.1 867.6 870.6 855.1 857.2 860.8 861.1 861.2 630.6 634.1 634.8 635.6 635.8 13117.7 13201.5 13210.8 13299.0 14641.6 12980.2 13502.3 13558.4 13614.9 13624.4 10509.9 10648.8 10810.0 10864.5 10921.9 3189.3 3223.3 3247.2 3247.8 3285.7 64576.4 64908.7 65351.7 65504.3 66355.3 9768.0 9866.5 9889.7 10108.9 10160.0 18965.8 19010.7 19090.7 19113.2 19152.6 6389.2 6742.4 6841.5 6877.4 6921.1 3488.2 3597.6 3598.7 3627.1 3657.8 2893.2 2959.6 2971.9 2976.2 2976.9 474.9 489.1 489.9 493.7 502.3 508.4 539.7 542.5 546.1 548.7 8035.6 8176.5 8386.4 8477.8 8570.9 566.2 593.1 596.5 602.9 604.2 3951.4 4193.5 4261.9 4279.1 4344.6 50493.0 51280.0 52708.0 53737.0 68608.0 56929.0 57309.0 58695.7 59768.1 59778.0 52453.5 53110.2 53172.7 53267.5 53477.0 7981.4 8162.1 8211.9 8224.3 8348.2 36816.6 37034.0 37202.8 37732.4 38310.0 45459.5 47185.5 47284.8 47388.3 49011.3 9512.3 9919.7 10023.4 11626.3 11887.2 12573.4 13233.7 13259.4 13297.8 13946.7 12106.8 12227.4 12282.0 12777.3 13347.0 11213.9 11436.5 11457.4 11666.7 13069.0 11173.6 11183.4 11900.0 12066.0 12120.9 9506.0 10104.5 10374.5 10478.6 10577.3 9825.4 10102.4 10150.2 10344.7 10416.7 17:50:01 INFO - PROCESS | 7551 | -------- Summary: end -------- 17:50:01 INFO - PROCESS | 7551 | 17:50:02 INFO - TEST-INFO | 7551: exit 0 17:50:02 INFO - TEST-OK | dromaeo_css | took 1119705ms 17:50:02 INFO - TEST-START | kraken 17:50:02 INFO - operating with platform_type : linux_ 17:50:02 INFO - Initialising browser for kraken test... 17:50:02 INFO - TEST-INFO | started process 7907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps4dVBT/profile http://localhost:50477/getInfo.html) 17:50:02 INFO - PROCESS | 7907 | 1483149002763 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:50:06 INFO - PROCESS | 7907 | __metrics Screen width/height:1600/1200 17:50:06 INFO - PROCESS | 7907 | colorDepth:24 17:50:06 INFO - PROCESS | 7907 | Browser inner width/height: 1024/697 17:50:06 INFO - PROCESS | 7907 | __metrics 17:50:07 ERROR - PROCESS | 7907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 17:50:08 INFO - TEST-INFO | 7907: exit 0 17:50:08 INFO - Browser initialized. 17:50:08 INFO - Running cycle 1/1 for kraken test... 17:50:08 INFO - TEST-INFO | started process 8027 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps4dVBT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:50:20 INFO - PROCESS | 8027 | RSS: Main: 158158848 17:50:20 INFO - PROCESS | 8027 | 17:50:23 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50477/tests/kraken/driver.html?testName=audio-beat-detection) 17:50:24 INFO - PROCESS | 8027 | RSS: Main: 164184064 17:50:24 INFO - PROCESS | 8027 | 17:50:26 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50477/tests/kraken/driver.html?testName=audio-dft) 17:50:27 INFO - PROCESS | 8027 | RSS: Main: 163450880 17:50:27 INFO - PROCESS | 8027 | 17:50:30 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50477/tests/kraken/driver.html?testName=audio-fft) 17:50:30 INFO - PROCESS | 8027 | RSS: Main: 159432704 17:50:30 INFO - PROCESS | 8027 | 17:50:33 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50477/tests/kraken/driver.html?testName=audio-oscillator) 17:50:33 INFO - PROCESS | 8027 | RSS: Main: 160088064 17:50:33 INFO - PROCESS | 8027 | 17:50:35 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50477/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:50:36 INFO - PROCESS | 8027 | RSS: Main: 160890880 17:50:36 INFO - PROCESS | 8027 | 17:50:39 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50477/tests/kraken/driver.html?testName=imaging-darkroom) 17:50:40 INFO - PROCESS | 8027 | RSS: Main: 165588992 17:50:40 INFO - PROCESS | 8027 | 17:50:43 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50477/tests/kraken/driver.html?testName=imaging-desaturate) 17:50:44 INFO - PROCESS | 8027 | RSS: Main: 162131968 17:50:44 INFO - PROCESS | 8027 | 17:50:47 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50477/tests/kraken/driver.html?testName=json-parse-financial) 17:50:48 INFO - PROCESS | 8027 | RSS: Main: 163151872 17:50:48 INFO - PROCESS | 8027 | 17:50:49 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50477/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:50:50 INFO - PROCESS | 8027 | RSS: Main: 163033088 17:50:50 INFO - PROCESS | 8027 | 17:50:52 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:50:52 INFO - PROCESS | 8027 | RSS: Main: 162758656 17:50:52 INFO - PROCESS | 8027 | 17:50:55 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:50:55 INFO - PROCESS | 8027 | RSS: Main: 163418112 17:50:55 INFO - PROCESS | 8027 | 17:50:58 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:50:59 INFO - PROCESS | 8027 | RSS: Main: 163758080 17:50:59 INFO - PROCESS | 8027 | 17:51:02 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:51:03 INFO - PROCESS | 8027 | RSS: Main: 163323904 17:51:03 INFO - PROCESS | 8027 | 17:51:04 INFO - PROCESS | 8027 | Cycle 1(1): loaded http://localhost:50477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50477/tests/kraken/driver.html?testName=ai-astar) 17:51:05 INFO - PROCESS | 8027 | RSS: Main: 164888576 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | __start_tp_report 17:51:05 INFO - PROCESS | 8027 | _x_x_mozilla_page_load 17:51:05 INFO - PROCESS | 8027 | _x_x_mozilla_page_load_details 17:51:05 INFO - PROCESS | 8027 | |i|pagename|runs| 17:51:05 INFO - PROCESS | 8027 | |0;ai-astar;108;117;117;119;116;116;116;116;116;116 17:51:05 INFO - PROCESS | 8027 | |1;audio-beat-detection;158;129;133;173;127;129;132;125;153;131 17:51:05 INFO - PROCESS | 8027 | |2;audio-dft;164;164;165;175;166;161;163;163;164;163 17:51:05 INFO - PROCESS | 8027 | |3;audio-fft;77;76;76;124;80;78;79;79;95;78 17:51:05 INFO - PROCESS | 8027 | |4;audio-oscillator;86;86;119;86;86;85;99;86;85;107 17:51:05 INFO - PROCESS | 8027 | |5;imaging-gaussian-blur;119;119;118;118;118;122;118;118;118;117 17:51:05 INFO - PROCESS | 8027 | |6;imaging-darkroom;151;150;150;150;150;152;150;152;152;151 17:51:05 INFO - PROCESS | 8027 | |7;imaging-desaturate;96;97;96;96;97;96;96;97;96;96 17:51:05 INFO - PROCESS | 8027 | |8;json-parse-financial;66;65;65;65;66;68;68;66;65;66 17:51:05 INFO - PROCESS | 8027 | |9;json-stringify-tinderbox;69;67;67;68;68;68;68;67;67;68 17:51:05 INFO - PROCESS | 8027 | |10;stanford-crypto-aes;87;88;90;86;85;86;87;91;85;85 17:51:05 INFO - PROCESS | 8027 | |11;stanford-crypto-ccm;140;137;136;153;135;137;134;135;188;151 17:51:05 INFO - PROCESS | 8027 | |12;stanford-crypto-pbkdf2;180;182;184;182;183;183;184;190;186;179 17:51:05 INFO - PROCESS | 8027 | |13;stanford-crypto-sha256-iterative;76;75;71;75;73;71;72;72;70;76 17:51:05 INFO - PROCESS | 8027 | __end_tp_report 17:51:05 INFO - PROCESS | 8027 | __start_cc_report 17:51:05 INFO - PROCESS | 8027 | _x_x_mozilla_cycle_collect,825 17:51:05 INFO - PROCESS | 8027 | __end_cc_report 17:51:05 INFO - PROCESS | 8027 | __startTimestamp1483149065195__endTimestamp 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | ------- Summary: start ------- 17:51:05 INFO - PROCESS | 8027 | Number of tests: 14 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#0] ai-astar Cycles:10 Average:115.70 Median:116.00 stddev:2.87 (2.5%) stddev-sans-first:1.01 17:51:05 INFO - PROCESS | 8027 | Values: 108.0 117.0 117.0 119.0 116.0 116.0 116.0 116.0 116.0 116.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:132.50 stddev:16.34 (12.3%) stddev-sans-first:15.81 17:51:05 INFO - PROCESS | 8027 | Values: 158.0 129.0 133.0 173.0 127.0 129.0 132.0 125.0 153.0 131.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:3.82 (2.3%) stddev-sans-first:4.04 17:51:05 INFO - PROCESS | 8027 | Values: 164.0 164.0 165.0 175.0 166.0 161.0 163.0 163.0 164.0 163.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#3] audio-fft Cycles:10 Average:84.20 Median:78.50 stddev:15.04 (19.2%) stddev-sans-first:15.72 17:51:05 INFO - PROCESS | 8027 | Values: 77.0 76.0 76.0 124.0 80.0 78.0 79.0 79.0 95.0 78.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:86.00 stddev:11.92 (13.9%) stddev-sans-first:12.41 17:51:05 INFO - PROCESS | 8027 | Values: 86.0 86.0 119.0 86.0 86.0 85.0 99.0 86.0 85.0 107.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:118.00 stddev:1.35 (1.1%) stddev-sans-first:1.42 17:51:05 INFO - PROCESS | 8027 | Values: 119.0 119.0 118.0 118.0 118.0 122.0 118.0 118.0 118.0 117.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 17:51:05 INFO - PROCESS | 8027 | Values: 151.0 150.0 150.0 150.0 150.0 152.0 150.0 152.0 152.0 151.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:51:05 INFO - PROCESS | 8027 | Values: 96.0 97.0 96.0 96.0 97.0 96.0 96.0 97.0 96.0 96.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:66.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 17:51:05 INFO - PROCESS | 8027 | Values: 66.0 65.0 65.0 65.0 66.0 68.0 68.0 66.0 65.0 66.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.53 17:51:05 INFO - PROCESS | 8027 | Values: 69.0 67.0 67.0 68.0 68.0 68.0 68.0 67.0 67.0 68.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:2.11 (2.4%) stddev-sans-first:2.24 17:51:05 INFO - PROCESS | 8027 | Values: 87.0 88.0 90.0 86.0 85.0 86.0 87.0 91.0 85.0 85.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:138.50 stddev:16.67 (12.0%) stddev-sans-first:17.60 17:51:05 INFO - PROCESS | 8027 | Values: 140.0 137.0 136.0 153.0 135.0 137.0 134.0 135.0 188.0 151.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.30 Median:183.50 stddev:3.09 (1.7%) stddev-sans-first:3.04 17:51:05 INFO - PROCESS | 8027 | Values: 180.0 182.0 184.0 182.0 183.0 183.0 184.0 190.0 186.0 179.0 17:51:05 INFO - PROCESS | 8027 | 17:51:05 INFO - PROCESS | 8027 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:74.00 stddev:2.23 (3.0%) stddev-sans-first:2.11 17:51:05 INFO - PROCESS | 8027 | Values: 76.0 75.0 71.0 75.0 73.0 71.0 72.0 72.0 70.0 76.0 17:51:05 INFO - PROCESS | 8027 | -------- Summary: end -------- 17:51:05 INFO - PROCESS | 8027 | 17:51:06 INFO - TEST-INFO | 8027: exit 0 17:51:06 INFO - TEST-OK | kraken | took 64102ms 17:51:06 INFO - SUITE-END | took 1183s 17:51:06 INFO - Completed test suite (00:19:44) 17:51:06 INFO - javascript benchmark 17:51:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6250.278041420473, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2546.287039628904, 2604.3879688273255, 2614.856182909939, 2628.369191394066, 2641.418887844675, 4675.859724208271, 4792.041591681665, 4803.135435621591, 4809.591184749301, 4812.5909297709695, 4694.812207511704, 4784.234522476405, 4790.904181916359, 4818.27725841124, 4836.129496690594, 4361.105973275471, 4392.538783427742, 4397.890052748675, 4463.040446304044, 4506.098780243948, 4378.321360189176, 4383.364412160235, 4464.575865292792, 4480.955190448087, 4486.506484286726, 4273.252180868367, 4282.357646353041, 4283.271843786564, 4451.310046942729, 4463.776811159429, 4328.27768119417, 4339.218940590692, 4361.781910904442, 4362.934555981631, 4363.650907927358, 4302.010537576343, 4315.978420107911, 4377.5403582624, 4410.647148228134, 4429.579189976947, 4682.906341873178, 4712.246040633517, 4724.433068031858, 4732.526747325274, 4827.25177597473, 5099.056674515226, 5113.718745468997, 5117.283580298761, 5120.795168193268, 5178.326817513736, 5017.49825017499, 5059.392872855281, 5111.335526381584, 5126.077306084903, 5160.354955630546, 4826.227803551415, 4833.0, 4838.516148385133, 4852.636052296092, 4855.441624213226, 5269.657472264291, 5285.9735701322015, 5295.073362161606, 5311.893762124736, 5317.8404647860625, 4860.198067318853, 4896.436909755353, 4907.337509436252, 4911.631627627942, 5032.119379108642, 4928.778204980713, 5019.322391477104, 5086.949130508721, 5098.796048158107, 5105.974470127626, 4941.03649788295, 5002.1496345621335, 5031.547160755549, 5056.469070747592, 5071.923921141186, 5047.16721740916, 5059.848204553869, 5068.493150684902, 5071.239314102914, 5117.5394214520875, 4301.720388174821, 4341.240282950471, 4342.652587792969, 4343.67422443318, 4390.429244198233, 3696.4270538066644, 3710.165212827092, 3727.5713292971654, 3808.8286027128847, 3825.885223443301, 4439.911201775946, 4460.420145381079, 4461.263891457873, 4489.326601009875, 4491.528389519119, 5131.820386286462, 5147.356580427447, 5156.381234251914, 5160.045391602565, 5212.843614691566, 4345.326474396474, 4390.62679672225, 4395.0, 4418.558144185556, 4445.533219012022, 4604.746738929391, 4608.585227329557, 4630.259158534618, 4644.187267228222, 4647.883802904955, 4770.522947705271, 4779.665423420327, 4813.614910807057, 4820.638452116105, 4859.829905953275, 5021.799128034838, 5068.493150684902, 5091.923621145612, 5147.974260128675, 5148.382194136728, 4755.881102972453, 4788.209945358977, 4791.281307803857, 4800.399950006249, 4845.903081938412, 5012.223105418666, 5014.473480284518, 5033.968036552425, 5087.829799146143, 5091.389033316026, 4933.531314525115, 5074.720890350918, 5154.561862241601, 5156.20078887773, 5213.81751638706, 4985.277134815359, 4987.675801072919, 4990.72551009698, 4999.825006124768, 5002.349694539686, 4954.9752251238515, 4956.430010548689, 4956.529129732669, 4957.950420495749, 4961.925571116509, 4682.274247491644, 4913.828016019565, 4914.36113305268, 4917.164082105979, 4956.083124621957, 4983.526564976321, 5042.067217564762, 5052.0, 5069.645124841226, 5121.539061484485, 4818.638602104786, 4887.975560122177, 4926.753662316942, 4941.061198372297, 4985.252212168204], "value": 4683.970916596276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.5634710496078, 523.1211564571519, 530.0194639916153, 534.5082524077847, 534.8956953394089, 36030.459543106845, 37971.0, 38081.42877856834, 38101.428478572845, 41391.0, 38735.61264387355, 39211.41182882252, 39318.410223846666, 39340.40989385162, 39397.0, 7580.431467639935, 7730.574818384986, 7737.613119344011, 9408.15326620604, 9708.320417570772, 9603.80792384152, 9704.0, 9704.854427183598, 9714.174295184917, 9714.465704386292, 9644.807103857918, 9698.563564639573, 9713.32006759527, 9717.757056073619, 9717.90282097177, 9603.90396096044, 9641.373310734814, 9700.223982081416, 9700.563474643657, 9707.320487565908, 9414.905850941472, 9509.144177024033, 9521.238300935978, 9525.57134928923, 9530.142287194185, 32517.0, 32846.0, 32954.50568241478, 32955.50566741501, 32982.835085824416, 3448.5516882805327, 3475.6350583188655, 3500.3524347995703, 3512.3150985557827, 3527.770694904849, 25007.70033285317, 25204.495910081878, 25564.616530752053, 25582.616260755916, 25715.22854314355, 3360.462326027848, 3401.3470090231617, 3413.351463222005, 3464.064702530328, 3467.9479807802904, 224266.0, 228465.0, 230222.0, 230953.9999999983, 231336.0, 44504.77747611241, 44659.553404465536, 44750.0, 44802.0, 44839.327410088874, 46121.308180377324, 46217.30674039892, 46421.535784641725, 46433.767831161305, 46475.0, 10902.345859248311, 11280.492377843017, 11335.489902954387, 11337.319760814378, 11363.943180284046, 7955.562444065632, 8174.692049272088, 8185.140560241208, 8381.49711017341, 8400.747977560683, 15239.390424383122, 15257.771133433007, 15289.235538223043, 15386.615334616725, 15487.760979121644, 6539.411452969255, 6628.23775265841, 6661.600303981777, 6747.763828265987, 6761.966190169018, 785.3089281432303, 787.5865170785269, 788.66087582339, 796.4663675337538, 810.878368244768, 25940.481190376086, 26985.595216071775, 27490.0, 27560.0, 27661.30846728848, 909.3043821476575, 922.7047344849718, 933.2254228989922, 938.779386844091, 1077.6012875236213, 14189.716205676035, 14358.641033974025, 14389.78415323771, 14534.0, 14590.0, 344907.0, 345245.0, 345892.0, 346967.0, 347998.0, 47149.0, 47846.0, 47907.9999999993, 47974.5202547977, 48023.0, 1135.2223726747204, 1177.8763229860913, 1192.0880526397314, 1199.196538319328, 1206.6259459567561, 25252.494950101263, 25767.742322576534, 26153.869230654487, 26642.20073397801, 26727.86636066807, 38380.2323953525, 38975.415368768925, 39399.21201575895, 39508.802455987534, 39552.66039084687, 297197.0, 298153.0, 298642.0, 298750.0, 300103.0, 22268.554628907656, 23062.538749225256, 23067.307980760605, 24260.75739242585, 24497.26508204757, 34639.653603463645, 35135.64864351324, 35381.64618353886, 35777.82111089428, 36261.81869090637, 29935.0, 30031.54952675668, 30261.697383026767, 30864.845675771478, 31113.53329700011, 30075.69924300729, 30408.239794004443, 30715.539266911463, 30901.8454907724, 31798.364032719677, 30416.847915761165, 30473.238169045064, 30514.694853051184, 30543.694563054087, 30633.54049689301, 26499.735002650497, 27846.58230126509, 27957.860210698815, 28183.859080704464, 28474.145775626763, 21778.56442871102, 21930.0, 22024.229151979605, 22235.99938002826, 22243.888780555993], "value": 16495.069207240384, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.6315353070164, 368.67556550235787, 375.6281281531284, 391.06730447881796, 395.3555704202152, 15335.38658453662, 15890.76163857543, 16386.0, 16405.589860253483, 16415.17924103796, 15498.380064797422, 16281.918590407062, 16313.673726525461, 16497.0, 16597.253123609436, 4845.394325709286, 5112.795488180468, 5120.283160357534, 5120.436751957282, 5143.588512918957, 5044.571211447032, 5173.275741396207, 5365.624406291561, 5409.56723462122, 5532.529734972532, 5476.288082549263, 5502.477264659851, 5515.172724091399, 5521.834344969638, 5531.0, 5355.758990845421, 5502.669839809624, 5519.31008623922, 5526.889462210774, 5544.944550554484, 5268.130758424855, 5343.973280133613, 5345.4654534546235, 5348.705821179834, 5351.357837059578, 19133.521661958424, 19380.4185874424, 19567.902160489248, 19773.802261977486, 19788.70316945247, 2038.1337931379105, 2043.8160565549024, 2133.5839511295303, 2157.1802714968367, 2185.715856938604, 8653.307735381155, 9148.679796207101, 9221.953890230572, 9392.436453812794, 9435.811283774356, 1690.1211370087608, 1767.3372485318005, 1770.2918832466987, 1772.884762490447, 1787.6603445345343, 60392.39607603912, 60930.695346523426, 62029.6898515509, 62520.68739656318, 64173.358266417206, 9360.344775865624, 9419.387739796892, 9580.377275477073, 9631.133198012212, 10280.948595256976, 10691.144708423462, 11059.502322395512, 11094.722631934104, 13248.536301229411, 13314.134581252187, 5928.258967629046, 6023.0, 6058.12157237207, 6216.595921265103, 6267.62394256346, 5719.857003574861, 5759.568032397587, 5872.588918775645, 5925.911111333334, 5938.584299099108, 3559.4549896823896, 3588.0671025533547, 3627.1657518770303, 3712.777233366007, 3721.683656889141, 1728.5764987577872, 1758.824117588231, 1761.1898526677614, 1790.3823181002533, 1835.2016872660436, 950.0126569084423, 954.5275088831073, 969.7963427680126, 976.3019441099648, 1020.0819262663662, 15418.0, 15695.0, 15731.370745170065, 15831.525054248392, 16266.0, 950.1591091883732, 951.0727041134976, 954.9665761698308, 956.4404823178519, 980.0, 8576.742697719079, 8998.325125615605, 9079.50062746542, 9213.262938964866, 9362.0, 248320.0, 250546.0, 250858.0, 295619.0, 345016.0, 67037.66481167563, 67230.66384668143, 68159.3184068163, 69578.0, 75782.0, 900.1808354397467, 918.3663272342196, 963.0465838819518, 966.9605174437507, 967.9564419601135, 14858.700100821701, 14883.47907823221, 14955.626109347353, 14984.016850452237, 15509.5347139586, 28219.0, 35218.64781352154, 35662.465063024596, 36136.8193159043, 36953.815230924745, 45227.32159017552, 46218.53781462277, 46284.305735414666, 46458.53541464542, 47375.28937066015, 7224.313690199423, 7289.5261807982315, 7398.593077380796, 7435.405167586472, 7557.470977031555, 8444.619992100494, 10512.526936288039, 11251.268667536584, 11556.47995840206, 11658.88341116612, 9634.325597208128, 10204.744881378025, 10251.026152515498, 10286.434246116536, 10508.369497830156, 9938.254630902857, 9953.502324883872, 10262.948685256526, 10292.794144117226, 10573.101286390744, 9585.760355991157, 10203.132733717717, 10211.642592509226, 10368.792624147323, 10665.36007839532, 6506.544541882023, 6576.112224849584, 6702.329767023259, 7489.438292128221, 7743.148253691977, 9385.202257808163, 9911.0, 10003.299439095226, 10017.198624109908, 10047.095761381219], "value": 7842.778870550429, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1274.9553765618205, 1275.866034066423, 1284.7880099783536, 1285.665726911003, 1301.6290357248186, 6595.868082638344, 6814.727410903558, 6914.204866440361, 7339.5963222022765, 7374.041374621298, 7021.543599666018, 7198.4601154913325, 7207.17117531484, 7419.146798118218, 7455.813604659873, 2136.594047131048, 2191.51786606947, 2193.616117179495, 2195.3743183192714, 2206.2388475975845, 2139.711138996239, 2151.526664133888, 2178.9564208715815, 2186.092771499834, 2200.207925146954, 2113.936581902538, 2177.4556360909774, 2177.8148857347146, 2181.312886440774, 2181.7381914170323, 1885.58517126232, 1914.2343062774862, 1928.7299778031088, 1929.7008963610663, 1934.6807776716826, 2142.625040617901, 2162.9783702162936, 2193.6599827026835, 2194.5391467791783, 2197.187040794916, 7379.225181355934, 7410.184879663233, 7418.703251869919, 7435.814104647373, 7438.516496427769, 4484.484284307281, 4571.971306456021, 4581.060882519076, 4593.104344652794, 4603.470600880908, 2735.0, 2887.63904511936, 2900.129961011688, 2951.837648929308, 2962.1113665900143, 1143.091242462236, 1159.113278342069, 1168.2021558367662, 1192.320377384891, 1225.3995542184355, 9623.230141588656, 9721.562529686182, 9766.951165244129, 9779.608815647365, 9841.704748857546, 4067.9389809152885, 4119.876403707894, 4144.79276036197, 4171.478565179353, 4266.1254442839145, 8135.349172066339, 8142.715004974797, 8146.266835984671, 8157.1842815717955, 8170.0, 4532.9773351133035, 4544.727316361029, 4548.772561371984, 4561.452625684939, 4564.908701825945, 4081.53064896637, 4152.1072969311745, 4173.624373806311, 4177.498700156001, 4187.309093999456, 3513.4202856528386, 3583.569971603424, 3621.402468592705, 3715.6655900968503, 3717.386631205821, 1129.4804389980536, 1161.0479406886272, 1173.196360493065, 1180.1738782852037, 1235.7960936445386, 645.2869579115104, 663.2869665110026, 674.6087269383746, 815.3517953227199, 829.8796674482167, 4623.352730617717, 4760.214564596872, 4816.638752093607, 4870.853874383774, 4957.082939656175, 842.2588122452203, 843.2200214801285, 843.5529169540154, 847.8304339132198, 871.5598622695625, 3205.487122060459, 3299.5875515560556, 3347.866085356607, 3371.763976521669, 3382.2389962258294, 8469.153084691605, 8689.652413903374, 8762.0, 8773.210411063035, 8778.166074222938, 8746.169113934166, 8808.471491710518, 8814.559272036373, 8827.24968377695, 8833.0, 6761.3238676132, 6807.65961701913, 6870.27862074485, 6935.133108361354, 6947.228456743498, 4686.203345431353, 4693.202155633482, 4729.929051064168, 4763.380760501252, 4842.443119041214, 7967.601619918865, 8003.719869804529, 8034.357251419756, 8087.150849160754, 8098.838023239385, 7723.227677232232, 8007.559584222924, 8012.038555373392, 8104.662730649613, 8127.593620318842, 6330.082138089859, 6444.2911279758255, 6452.709628066655, 6453.548251622341, 6456.870862582816, 6953.930460695328, 6957.2694867038235, 6991.8113920632595, 7000.929990700231, 7003.824904377431, 6348.396902294275, 6426.807195784134, 6477.870442591027, 6527.934720652733, 6552.213734351908, 6327.11420401135, 6346.270178929298, 6374.4900407966325, 6376.023432735867, 6557.2131344239, 5705.115707065412, 5718.656880587178, 5748.338941021836, 5754.6547207167705, 5761.798337058183, 4171.311733563928, 4181.770002649883, 4257.212415703105, 4265.808038638331, 4346.543612920598, 4217.409562661169, 4249.447571815645, 4253.149370126049, 4301.612854843079, 4407.118576284692], "value": 3935.798121018201, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.7163182926341, 355.6141586378779, 362.0839276630127, 364.1497104261549, 364.1715098151706, 4906.337644418002, 4941.9199608639865, 4993.001399720057, 5016.673916195449, 5043.420006699231, 4823.927641085386, 4896.583790377822, 4957.380327459067, 4960.355153829997, 4970.950290497094, 3958.4656071430422, 3960.9603903961024, 3973.3046716824583, 3990.64084232419, 4016.7389119707273, 3902.0, 4006.3589825627905, 4012.879613611596, 4016.5581786003518, 4016.7021287222683, 3940.901477463072, 3964.6431821136093, 3982.9203415931665, 3983.780892050936, 3986.4851356484496, 3947.4538922395036, 3957.6635985941084, 3958.9208215835524, 3969.384745364473, 3977.9657289104753, 3578.4095624221973, 3591.0, 3597.2086141048926, 3608.8015159166234, 3624.4925710400566, 4669.813207471697, 4841.298011788306, 4871.805127794884, 4886.828960986384, 4902.901941961177, 4430.8449204277695, 4479.664025198123, 4491.562699936022, 4508.977455112704, 4520.728756274601, 5174.275601415805, 5189.351331083614, 5191.454897235812, 5199.428062913114, 5218.530332270076, 3778.9433158502643, 3890.980545097285, 3930.3885406983286, 3933.8426462941447, 3944.447777311179, 5387.73061346935, 5398.921415255116, 5408.296386638236, 5440.857419941817, 5484.561235101182, 3969.8809035728973, 3975.582563830814, 3983.900402489961, 3997.980010099931, 4006.7996600169877, 4104.548499665035, 4109.938350924738, 4113.814878330482, 4116.382542618631, 4123.195976784558, 4091.8977025574004, 4261.808218630169, 4291.763952982616, 4316.697831151852, 4317.503487098961, 5176.896462070813, 5186.766595503212, 5196.53231209193, 5203.947960520422, 5220.791168353299, 1218.5065048655308, 1254.5483625894672, 1263.810428435742, 1265.911386202957, 1273.9171953822972, 866.7486428935664, 876.3305902256212, 879.2394578689388, 879.463527248378, 881.5239770523973, 4400.623663221888, 4416.138852923649, 4419.160359531678, 4426.18115648606, 4430.0, 5154.427899490616, 5158.613104017214, 5196.480351964773, 5212.478752124757, 5220.381681678692, 4342.41377414045, 4530.365748795171, 4592.977035114803, 4594.954050459452, 4605.424321959755, 2756.476269508787, 2761.4891245119716, 2764.350377661248, 2785.247983044567, 2786.3452088759127, 4747.715137091785, 4795.20879054952, 4840.0, 4842.6368022398465, 4850.975745121252, 5263.973680131651, 5275.57795376369, 5324.9733751331, 5340.739371718854, 5346.8128615499045, 4857.7085374876915, 4870.610351171968, 4874.7562621868765, 4881.755912204376, 4894.216925291937, 5043.773030213576, 5128.820491282787, 5196.766145523537, 5197.064528384927, 5232.6395137265345, 5125.589952803842, 5126.307948426915, 5142.5885929126325, 5152.149895267239, 5154.974225128851, 4838.830640927691, 4853.466118726867, 4864.927026094682, 4903.485134060872, 4931.72875491837, 3844.9807750961068, 3883.728139030241, 3929.7011248419994, 3944.6055394460323, 3984.442178095127, 5159.277701121771, 5168.328117344721, 5172.456892026283, 5196.922046169386, 5214.322138122172, 5031.421386540448, 5043.873903152304, 5074.270411247804, 5074.467180945948, 5101.69389836611, 4818.397700287464, 4971.502849715144, 5007.273945277841, 5044.4451110377095, 5050.292958985671, 4949.57928576075, 4955.82654607087, 4977.726225057657, 5093.185090385668, 5127.92308115386, 5216.843494695166, 5220.295260139833, 5224.425313215619, 5227.712475813714, 5277.234800953764, 5151.871203219949, 5160.303359046481, 5160.741962901915, 5167.534921856901, 5170.870728231825], "value": 3926.3726868301273, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.689588997281, 660.2341284110432, 725.231254869838, 745.4409193105171, 761.5849362097656, 12067.336296503689, 12996.54512092077, 14421.49524766632, 14963.102213867176, 14979.0, 15235.542933711979, 15280.770788438182, 15308.081515109101, 15431.845681543182, 15512.379504819823, 664.9601023938579, 810.5541951926432, 839.9664013439456, 841.4236248170023, 843.4770442325736, 665.5241502325836, 793.3811626931004, 808.9393295502831, 825.4428260923883, 825.5129473610568, 865.485036403339, 866.5797088412147, 867.1112110087148, 867.6312567158964, 870.6212797433137, 855.0978717453085, 857.1557016338901, 860.7632900952226, 861.0958493581714, 861.2335021830576, 630.5649102119569, 634.0647544871277, 634.815903388017, 635.5995722694719, 635.7806556737918, 13117.737645247042, 13201.537946171838, 13210.801837972438, 13299.0, 14641.633959151, 12980.15628984113, 13502.257375844423, 13558.389872455664, 13614.93192534031, 13624.386902589407, 10509.905813575806, 10648.840267395995, 10810.0, 10864.511097000654, 10921.945390272998, 3189.346184032268, 3223.2908760072746, 3247.1882029492626, 3247.8376081196084, 3285.737141028712, 64576.35423645798, 64908.67545662241, 65351.673241633966, 65504.3449565503, 66355.3364466354, 9767.951160244153, 9866.457344846101, 9889.653862114936, 10108.916780381907, 10160.0, 18965.810341896406, 19010.71483927742, 19090.713639295423, 19113.235470581298, 19152.61694766125, 6389.169407976933, 6742.393184613408, 6841.486888483384, 6877.3810357068105, 6921.1002569666625, 3488.180277634754, 3597.568291805, 3598.7480876338927, 3627.147620309225, 3657.817109144532, 2893.23329317731, 2959.5708622249654, 2971.9108426746798, 2976.241058530061, 2976.866041028159, 474.8931490414629, 489.0732062741086, 489.85059556835506, 493.7481884238992, 502.25164504887454, 508.4204007431531, 539.6589475154238, 542.4900593442152, 546.0880329849161, 548.7475761149836, 8035.558044307502, 8176.509409435453, 8386.371022173362, 8477.788055298666, 8570.871436928452, 566.2242727463401, 593.1310629927177, 596.5496050481945, 602.8643555200133, 604.241676695744, 3951.4467974483596, 4193.454850869368, 4261.91476170481, 4279.101388708344, 4344.587264209895, 50493.0, 51280.0, 52708.0, 53737.0, 68608.0, 56929.0, 57309.0, 58695.706521467975, 59768.10347844786, 59778.0, 52453.475465245625, 53110.20334694983, 53172.73413632907, 53267.46732532625, 53477.0, 7981.3614910808155, 8162.142974987541, 8211.917880821115, 8224.259816616533, 8348.248657620843, 36816.63183368132, 37034.0, 37202.8139859299, 37732.43401349037, 38310.0, 45459.54540454553, 47185.52814471949, 47284.7635761819, 47388.289175663085, 49011.264831028275, 9512.33413661037, 9919.65281215154, 10023.448710321003, 11626.302421854716, 11887.16789824704, 12573.434195461408, 13233.735325293634, 13259.403326850508, 13297.80053299182, 13946.721065578835, 12106.75786484283, 12227.388630568614, 12282.01743860507, 12777.297248651414, 13347.0, 11213.943930280297, 11436.485358159069, 11457.427128643702, 11666.650000499998, 13069.019823513085, 11173.553057877594, 11183.384913829492, 11900.04799616046, 12066.040880080673, 12120.93939530332, 9505.952470237604, 10104.494775261355, 10374.481275936023, 10478.63324783629, 10577.259591828499, 9825.4104753715, 10102.39385636864, 10150.238732094975, 10344.689659310232, 10416.687499375032], "value": 6366.294290309868, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 117.0, 119.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 129.0, 133.0, 173.0, 127.0, 129.0, 132.0, 125.0, 153.0, 131.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 175.0, 166.0, 161.0, 163.0, 163.0, 164.0, 163.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 124.0, 80.0, 78.0, 79.0, 79.0, 95.0, 78.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 119.0, 86.0, 86.0, 85.0, 99.0, 86.0, 85.0, 107.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 118.0, 118.0, 118.0, 122.0, 118.0, 118.0, 118.0, 117.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 150.0, 150.0, 152.0, 150.0, 152.0, 152.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 68.0, 68.0, 66.0, 65.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 68.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 90.0, 86.0, 85.0, 86.0, 87.0, 91.0, 85.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 136.0, 153.0, 135.0, 137.0, 134.0, 135.0, 188.0, 151.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 182.0, 184.0, 182.0, 183.0, 183.0, 184.0, 190.0, 186.0, 179.0], "value": 183.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 71.0, 75.0, 73.0, 71.0, 72.0, 72.0, 70.0, 76.0], "value": 73.1, "unit": "score"}]}]} 17:51:06 INFO - Return code: 0 17:51:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:51:06 ERROR - # TBPL SUCCESS # 17:51:06 INFO - Running post-action listener: _resource_record_post_action 17:51:06 INFO - [mozharness: 2016-12-31 01:51:06.975847Z] Finished run-tests step (success) 17:51:06 INFO - Running post-run listener: _resource_record_post_run 17:51:07 INFO - Total resource usage - Wall time: 1201s; CPU: 14.0%; Read bytes: 4096; Write bytes: 395636736; Read time: 12; Write time: 248324 17:51:07 INFO - TinderboxPrint: CPU usage
13.5% 17:51:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:51:07 INFO - TinderboxPrint: I/O write bytes / time
395,636,736 / 248,324 17:51:07 INFO - TinderboxPrint: CPU idle
8,286.0 (86.5%) 17:51:07 INFO - TinderboxPrint: CPU user
1,237.7 (12.9%) 17:51:07 INFO - TinderboxPrint: Swap in / out
0 / 0 17:51:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100024320; Read time: 0; Write time: 85744 17:51:07 INFO - run-tests - Wall time: 1185s; CPU: 14.0%; Read bytes: 0; Write bytes: 295612416; Read time: 0; Write time: 162580 17:51:07 INFO - Running post-run listener: _upload_blobber_files 17:51:07 INFO - Blob upload gear active. 17:51:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:51:07 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 17:51:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 17:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:08 INFO - (blobuploader) - INFO - Done attempting. 17:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:09 INFO - (blobuploader) - INFO - Done attempting. 17:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:13 INFO - (blobuploader) - INFO - Done attempting. 17:51:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:51:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:13 INFO - (blobuploader) - INFO - Done attempting. 17:51:13 INFO - (blobuploader) - INFO - Iteration through files over. 17:51:14 INFO - Return code: 0 17:51:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:51:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:51:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e27a7e8cafeb2b89e2c232705beaa968605d0db91be9f50548ddf2648ffd6ef7908d87418104575598283332c619ab6ac71e68fce2289009ed4f49399b31e2ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cd8336e77bf39045bf4bff2ae3f92035018a8c86e6a31b7be074a3f2bc18ad9fb0f71d3addf1f06e4f6d2f9e327bdd0e9b88269c3768f820fe2effe8582304", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06868ee1bf35e8b4eb394b71f2a072643ddc81d415053d210bad31c23ec6747d18e354e605eebd6d5ecfda4ff7f7e7c4c09194ab3bc017a0f9828adac7c33305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c5964c233925edd817fcc6b2f37cdba98700b3b9ec7ea7c0d265ee46ce377dc803ec84bdabd9ba3b857f5ad4173dc3498f31c24945f49addfac566216bf2973"} 17:51:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:51:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:51:14 INFO - Contents: 17:51:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e27a7e8cafeb2b89e2c232705beaa968605d0db91be9f50548ddf2648ffd6ef7908d87418104575598283332c619ab6ac71e68fce2289009ed4f49399b31e2ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cd8336e77bf39045bf4bff2ae3f92035018a8c86e6a31b7be074a3f2bc18ad9fb0f71d3addf1f06e4f6d2f9e327bdd0e9b88269c3768f820fe2effe8582304", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06868ee1bf35e8b4eb394b71f2a072643ddc81d415053d210bad31c23ec6747d18e354e605eebd6d5ecfda4ff7f7e7c4c09194ab3bc017a0f9828adac7c33305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c5964c233925edd817fcc6b2f37cdba98700b3b9ec7ea7c0d265ee46ce377dc803ec84bdabd9ba3b857f5ad4173dc3498f31c24945f49addfac566216bf2973"} 17:51:14 INFO - Running post-run listener: copy_logs_to_upload_dir 17:51:14 INFO - Copying logs to upload dir... 17:51:14 INFO - mkdir: /builds/slave/test/build/upload/logs 17:51:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.368159 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-30 17:51:13.973401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 17:51:13.976778) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e27a7e8cafeb2b89e2c232705beaa968605d0db91be9f50548ddf2648ffd6ef7908d87418104575598283332c619ab6ac71e68fce2289009ed4f49399b31e2ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cd8336e77bf39045bf4bff2ae3f92035018a8c86e6a31b7be074a3f2bc18ad9fb0f71d3addf1f06e4f6d2f9e327bdd0e9b88269c3768f820fe2effe8582304", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06868ee1bf35e8b4eb394b71f2a072643ddc81d415053d210bad31c23ec6747d18e354e605eebd6d5ecfda4ff7f7e7c4c09194ab3bc017a0f9828adac7c33305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c5964c233925edd817fcc6b2f37cdba98700b3b9ec7ea7c0d265ee46ce377dc803ec84bdabd9ba3b857f5ad4173dc3498f31c24945f49addfac566216bf2973"} build_url:https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/UVhxwU_0RUeMWvx5RQXSXA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e27a7e8cafeb2b89e2c232705beaa968605d0db91be9f50548ddf2648ffd6ef7908d87418104575598283332c619ab6ac71e68fce2289009ed4f49399b31e2ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cd8336e77bf39045bf4bff2ae3f92035018a8c86e6a31b7be074a3f2bc18ad9fb0f71d3addf1f06e4f6d2f9e327bdd0e9b88269c3768f820fe2effe8582304", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06868ee1bf35e8b4eb394b71f2a072643ddc81d415053d210bad31c23ec6747d18e354e605eebd6d5ecfda4ff7f7e7c4c09194ab3bc017a0f9828adac7c33305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c5964c233925edd817fcc6b2f37cdba98700b3b9ec7ea7c0d265ee46ce377dc803ec84bdabd9ba3b857f5ad4173dc3498f31c24945f49addfac566216bf2973"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 17:51:14.005096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:51:14.005430) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483147826.993176-239612691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:51:14.091989) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 17:51:14.092829) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 17:51:14.093455) ========= ========= Total master_lag: 0.17 =========