builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1486658813.88 results: success (0) revision: 1c204002b9bedaa9fb386dda2031e32d664d6871 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:53.876559) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:53.876989) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:53.877262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:53.893362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:53.893620) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 08:46:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.4M=0s 2017-02-09 08:46:54 (42.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120441 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:54.026966) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:54.027292) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020820 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:54.081241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 08:46:54.081682) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c204002b9bedaa9fb386dda2031e32d664d6871 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c204002b9bedaa9fb386dda2031e32d664d6871 --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=74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 08:46:54,485 truncating revision to first 12 chars 2017-02-09 08:46:54,486 Setting DEBUG logging. 2017-02-09 08:46:54,486 attempt 1/10 2017-02-09 08:46:54,486 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1c204002b9be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 08:46:55,606 unpacking tar archive at: mozilla-aurora-1c204002b9be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.377235 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 08:46:55.473833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:55.474395) ========= script_repo_revision: 1c204002b9bedaa9fb386dda2031e32d664d6871 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:55.475015) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:55.475492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 08:46:55.486577) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-02-09 08:46:55.487478) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-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=74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:46:55 INFO - MultiFileLogger online at 20170209 08:46:55 in /builds/slave/test 08:46:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 08:46:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:46:55 INFO - {'append_to_log': False, 08:46:55 INFO - 'base_work_dir': '/builds/slave/test', 08:46:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 08:46:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:46:55 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 08:46:55 INFO - 'buildbot_json_path': 'buildprops.json', 08:46:55 INFO - 'config_files': ('talos/linux_config.py',), 08:46:55 INFO - 'default_actions': ('clobber', 08:46:55 INFO - 'read-buildbot-config', 08:46:55 INFO - 'download-and-extract', 08:46:55 INFO - 'populate-webroot', 08:46:55 INFO - 'create-virtualenv', 08:46:55 INFO - 'install', 08:46:55 INFO - 'run-tests'), 08:46:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:46:55 INFO - 'download_minidump_stackwalk': True, 08:46:55 INFO - 'download_symbols': 'ondemand', 08:46:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:46:55 INFO - 'tooltool.py': '/tools/tooltool.py', 08:46:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:46:55 INFO - '/tools/misc-python/virtualenv.py')}, 08:46:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:46:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:46:55 INFO - 'installer_path': 'installer.exe', 08:46:55 INFO - 'log_level': 'info', 08:46:55 INFO - 'log_name': 'talos', 08:46:55 INFO - 'log_to_console': True, 08:46:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:46:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:46:55 INFO - 'opt_config_files': (), 08:46:55 INFO - 'pip_index': False, 08:46:55 INFO - 'sps_profile': False, 08:46:55 INFO - 'sps_profile_interval': 0, 08:46:55 INFO - 'suite': 'dromaeojs', 08:46:55 INFO - 'system_bits': '32', 08:46:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:46:55 INFO - 'title': 'talos-linux64-ix-042', 08:46:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:46:55 INFO - 'use_talos_json': True, 08:46:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:46:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:46:55 INFO - 'work_dir': 'build'} 08:46:55 INFO - [mozharness: 2017-02-09 16:46:55.990415Z] Running clobber step. 08:46:55 INFO - Running pre-action listener: _resource_record_pre_action 08:46:55 INFO - Running main action method: clobber 08:46:55 INFO - rmtree: /builds/slave/test/build 08:46:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:46:57 INFO - Running post-action listener: _resource_record_post_action 08:46:57 INFO - [mozharness: 2017-02-09 16:46:57.055270Z] Finished clobber step (success) 08:46:57 INFO - [mozharness: 2017-02-09 16:46:57.055371Z] Running read-buildbot-config step. 08:46:57 INFO - Running pre-action listener: _resource_record_pre_action 08:46:57 INFO - Running main action method: read_buildbot_config 08:46:57 INFO - Using buildbot properties: 08:46:57 INFO - { 08:46:57 INFO - "project": "", 08:46:57 INFO - "product": "firefox", 08:46:57 INFO - "who": "ffxbld@noreply.mozilla.org", 08:46:57 INFO - "installer_path": "public/build/target.tar.bz2", 08:46:57 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:46:57 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:46:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 08:46:57 INFO - "stage_platform": "linux64", 08:46:57 INFO - "basedir": "/builds/slave/test", 08:46:57 INFO - "buildnumber": 28, 08:46:57 INFO - "platform": "ubuntu64_hw", 08:46:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:46:57 INFO - "slavebuilddir": "test", 08:46:57 INFO - "taskId": "R8koVgyqRke5dZuEhlLd8Q", 08:46:57 INFO - "branch": "mozilla-aurora", 08:46:57 INFO - "script_repo_revision": "production", 08:46:57 INFO - "revision": "1c204002b9bedaa9fb386dda2031e32d664d6871", 08:46:57 INFO - "slavename": "talos-linux64-ix-042", 08:46:57 INFO - "repo_path": "releases/mozilla-aurora" 08:46:57 INFO - } 08:46:57 INFO - Finding installer, test and symbols from parent task. 08:46:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R8koVgyqRke5dZuEhlLd8Q'}, attempt #1 08:46:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R8koVgyqRke5dZuEhlLd8Q'}, attempt #1 08:46:58 INFO - Task dependencies: Sfm-yZw_Txq3_5zTsQbTxA 08:46:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA'}, attempt #1 08:46:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2 08:46:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/test_packages.json 08:46:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.crashreporter-symbols.zip 08:46:58 INFO - Running post-action listener: _resource_record_post_action 08:46:58 INFO - [mozharness: 2017-02-09 16:46:58.486985Z] Finished read-buildbot-config step (success) 08:46:58 INFO - [mozharness: 2017-02-09 16:46:58.487203Z] Running download-and-extract step. 08:46:58 INFO - Running pre-action listener: _resource_record_pre_action 08:46:58 INFO - Running main action method: download_and_extract 08:46:58 INFO - mkdir: /builds/slave/test/build/tests 08:46: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')]} 08:46:58 INFO - trying https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.test_packages.json 08:46:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:46:59 INFO - Downloaded 896 bytes. 08:46:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:46:59 INFO - Using the following test package requirements: 08:46:59 INFO - {u'common': [u'target.common.tests.zip'], 08:46:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:46:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:46:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:46:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:46:59 INFO - u'mozbase': [u'target.common.tests.zip'], 08:46:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:46:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:46:59 INFO - u'web-platform': [u'target.common.tests.zip', 08:46:59 INFO - u'target.web-platform.tests.zip'], 08:46:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:46:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:46:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.common.tests.zip 08:46:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:46:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.common.tests.zip into memory 08:46:59 INFO - Expected file size: 21858642 08:46:59 INFO - Obtained file size: 21858642 08:47:03 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:47:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.common.tests.zip 08:47:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:47:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.common.tests.zip into memory 08:47:04 INFO - Expected file size: 21858642 08:47:04 INFO - Obtained file size: 21858642 08:47:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.talos.tests.zip 08:47:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:47:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.talos.tests.zip into memory 08:47:10 INFO - Expected file size: 14403485 08:47:10 INFO - Obtained file size: 14403485 08:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:10 INFO - trying https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2 08:47:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:47:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:47:11 INFO - Downloaded 66129951 bytes. 08:47:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2 08:47:11 INFO - mkdir: /builds/slave/test/properties 08:47:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:47:11 INFO - Writing to file /builds/slave/test/properties/build_url 08:47:11 INFO - Contents: 08:47:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2 08:47:11 INFO - Running post-action listener: _resource_record_post_action 08:47:11 INFO - Running post-action listener: set_extra_try_arguments 08:47:11 INFO - [mozharness: 2017-02-09 16:47:11.519174Z] Finished download-and-extract step (success) 08:47:11 INFO - [mozharness: 2017-02-09 16:47:11.519271Z] Running populate-webroot step. 08:47:11 INFO - Running pre-action listener: _resource_record_pre_action 08:47:11 INFO - Running main action method: populate_webroot 08:47:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:47:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:47:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:47:11 INFO - '--tppagecycles', 08:47:11 INFO - '7'], 08:47:11 INFO - 'tests': ['tsvgm']}}, 08:47:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:47:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:47:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:47:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:47:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:47:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:47:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['damp', 'tps']}, 08:47:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'tests': ['damp', 'tps']}, 08:47:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['dromaeo_dom']}, 08:47:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:47:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['basic_compositor_video']}, 08:47:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:47:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['a11yr', 08:47:11 INFO - 'ts_paint', 08:47:11 INFO - 'tpaint', 08:47:11 INFO - 'sessionrestore', 08:47:11 INFO - 'sessionrestore_no_auto_restore', 08:47:11 INFO - 'tabpaint']}, 08:47:11 INFO - 'other-e10s': {'tests': ['a11yr', 08:47:11 INFO - 'ts_paint', 08:47:11 INFO - 'tpaint', 08:47:11 INFO - 'sessionrestore', 08:47:11 INFO - 'sessionrestore_no_auto_restore', 08:47:11 INFO - 'tabpaint']}, 08:47:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:47:11 INFO - 'ts_paint', 08:47:11 INFO - 'tpaint', 08:47:11 INFO - 'sessionrestore', 08:47:11 INFO - 'sessionrestore_no_auto_restore', 08:47:11 INFO - 'tabpaint']}, 08:47:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:47:11 INFO - 'ts_paint', 08:47:11 INFO - 'tpaint', 08:47:11 INFO - 'sessionrestore', 08:47:11 INFO - 'sessionrestore_no_auto_restore', 08:47:11 INFO - 'tabpaint']}, 08:47:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['a11yr', 08:47:11 INFO - 'ts_paint', 08:47:11 INFO - 'tpaint', 08:47:11 INFO - 'sessionrestore', 08:47:11 INFO - 'sessionrestore_no_auto_restore', 08:47:11 INFO - 'tabpaint']}, 08:47:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['a11yr', 08:47:11 INFO - 'ts_paint', 08:47:11 INFO - 'tpaint', 08:47:11 INFO - 'sessionrestore', 08:47:11 INFO - 'sessionrestore_no_auto_restore', 08:47:11 INFO - 'tabpaint']}, 08:47:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['tsvgx', 08:47:11 INFO - 'tsvgr_opacity', 08:47:11 INFO - 'tart', 08:47:11 INFO - 'tscrollx', 08:47:11 INFO - 'cart', 08:47:11 INFO - 'tsvg_static']}, 08:47:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:47:11 INFO - 'tsvgr_opacity', 08:47:11 INFO - 'tart', 08:47:11 INFO - 'tscrollx', 08:47:11 INFO - 'cart', 08:47:11 INFO - 'tsvg_static']}, 08:47:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'talos_options': ['--disable-e10s'], 08:47:11 INFO - 'tests': ['tp5o']}, 08:47:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:47:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'talos_options': ['--disable-e10s', 08:47:11 INFO - '--xperf_path', 08:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:47:11 INFO - 'tests': ['tp5n']}, 08:47:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:47:11 INFO - 'talos_options': ['--xperf_path', 08:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:47:11 INFO - 'tests': ['tp5n']}}, 08:47:11 INFO - 'talos.zip': {'path': '', 08:47:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:47:11 INFO - Running post-action listener: _resource_record_post_action 08:47:11 INFO - [mozharness: 2017-02-09 16:47:11.532625Z] Finished populate-webroot step (success) 08:47:11 INFO - [mozharness: 2017-02-09 16:47:11.532715Z] Running create-virtualenv step. 08:47:11 INFO - Running pre-action listener: _resource_record_pre_action 08:47:11 INFO - Running main action method: create_virtualenv 08:47:11 INFO - Creating virtualenv /builds/slave/test/build/venv 08:47:11 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 08:47:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:47:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:47:11 INFO - Using real prefix '/usr' 08:47:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:47:12 INFO - Installing distribute.............................................................................................................................................................................................done. 08:47:14 INFO - Installing pip.................done. 08:47:14 INFO - Return code: 0 08:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:47:14 INFO - Reading from file tmpfile_stdout 08:47:14 INFO - Output received: 08:47:14 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) 08:47:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:14 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:14 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 08:47:14 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 08:47:14 INFO - Using env: {'DISPLAY': ':0', 08:47:14 INFO - 'HOME': '/home/cltbld', 08:47:14 INFO - 'LANG': 'en_US.UTF-8', 08:47:14 INFO - 'LANGUAGE': 'en_US:en', 08:47:14 INFO - 'LOGNAME': 'cltbld', 08:47:14 INFO - 'MAIL': '/var/mail/cltbld', 08:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:14 INFO - 'MOZ_NO_REMOTE': '1', 08:47:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:14 INFO - 'NO_EM_RESTART': '1', 08:47:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:14 INFO - 'PWD': '/builds/slave/test', 08:47:14 INFO - 'SHELL': '/bin/bash', 08:47:14 INFO - 'SHLVL': '1', 08:47:14 INFO - 'TERM': 'linux', 08:47:14 INFO - 'TMOUT': '86400', 08:47:14 INFO - 'USER': 'cltbld', 08:47:14 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:14 INFO - '_': '/tools/buildbot/bin/python'} 08:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:14 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 08:47:14 INFO - Cleaning up... 08:47:14 INFO - Return code: 0 08:47:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:14 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:14 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 08:47:14 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 08:47:14 INFO - Using env: {'DISPLAY': ':0', 08:47:14 INFO - 'HOME': '/home/cltbld', 08:47:14 INFO - 'LANG': 'en_US.UTF-8', 08:47:14 INFO - 'LANGUAGE': 'en_US:en', 08:47:14 INFO - 'LOGNAME': 'cltbld', 08:47:14 INFO - 'MAIL': '/var/mail/cltbld', 08:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:14 INFO - 'MOZ_NO_REMOTE': '1', 08:47:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:14 INFO - 'NO_EM_RESTART': '1', 08:47:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:14 INFO - 'PWD': '/builds/slave/test', 08:47:14 INFO - 'SHELL': '/bin/bash', 08:47:14 INFO - 'SHLVL': '1', 08:47:14 INFO - 'TERM': 'linux', 08:47:14 INFO - 'TMOUT': '86400', 08:47:14 INFO - 'USER': 'cltbld', 08:47:14 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:14 INFO - '_': '/tools/buildbot/bin/python'} 08:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:14 INFO - Downloading/unpacking psutil>=3.1.1 08:47:14 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 08:47:14 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 08:47:14 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 08:47:14 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 08:47:14 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 08:47:14 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 08:47:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:47:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:16 INFO - Installing collected packages: psutil 08:47:16 INFO - Running setup.py install for psutil 08:47:16 INFO - building 'psutil._psutil_linux' extension 08:47:16 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 08:47:17 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 08:47:17 INFO - building 'psutil._psutil_posix' extension 08:47:17 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 08:47:17 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 08:47:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:17 INFO - Successfully installed psutil 08:47:17 INFO - Cleaning up... 08:47:17 INFO - Return code: 0 08:47:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:17 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:17 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 08:47:17 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 08:47:17 INFO - Using env: {'DISPLAY': ':0', 08:47:17 INFO - 'HOME': '/home/cltbld', 08:47:17 INFO - 'LANG': 'en_US.UTF-8', 08:47:17 INFO - 'LANGUAGE': 'en_US:en', 08:47:17 INFO - 'LOGNAME': 'cltbld', 08:47:17 INFO - 'MAIL': '/var/mail/cltbld', 08:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:17 INFO - 'MOZ_NO_REMOTE': '1', 08:47:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:17 INFO - 'NO_EM_RESTART': '1', 08:47:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:17 INFO - 'PWD': '/builds/slave/test', 08:47:17 INFO - 'SHELL': '/bin/bash', 08:47:17 INFO - 'SHLVL': '1', 08:47:17 INFO - 'TERM': 'linux', 08:47:17 INFO - 'TMOUT': '86400', 08:47:17 INFO - 'USER': 'cltbld', 08:47:17 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:17 INFO - '_': '/tools/buildbot/bin/python'} 08:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:47:17 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 08:47:17 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 08:47:17 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 08:47:17 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 08:47:17 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 08:47:17 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 08:47:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:47:19 INFO - Installing collected packages: mozsystemmonitor 08:47:19 INFO - Running setup.py install for mozsystemmonitor 08:47:19 INFO - Successfully installed mozsystemmonitor 08:47:19 INFO - Cleaning up... 08:47:19 INFO - Return code: 0 08:47:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:47:19 INFO - Using env: {'DISPLAY': ':0', 08:47:19 INFO - 'HOME': '/home/cltbld', 08:47:19 INFO - 'LANG': 'en_US.UTF-8', 08:47:19 INFO - 'LANGUAGE': 'en_US:en', 08:47:19 INFO - 'LOGNAME': 'cltbld', 08:47:19 INFO - 'MAIL': '/var/mail/cltbld', 08:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:19 INFO - 'MOZ_NO_REMOTE': '1', 08:47:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:19 INFO - 'NO_EM_RESTART': '1', 08:47:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:19 INFO - 'PWD': '/builds/slave/test', 08:47:19 INFO - 'SHELL': '/bin/bash', 08:47:19 INFO - 'SHLVL': '1', 08:47:19 INFO - 'TERM': 'linux', 08:47:19 INFO - 'TMOUT': '86400', 08:47:19 INFO - 'USER': 'cltbld', 08:47:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:19 INFO - '_': '/tools/buildbot/bin/python'} 08:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:20 INFO - Downloading/unpacking jsonschema==2.5.1 08:47:20 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 08:47:20 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 08:47:20 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 08:47:20 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 08:47:20 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 08:47:20 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 08:47:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:47:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 INFO - Downloading functools32-3.2.3-2.tar.gz 08:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:47:22 INFO - warning: no files found matching '*.txt' 08:47:22 INFO - no previously-included directories found matching 'build' 08:47:22 INFO - no previously-included directories found matching 'dist' 08:47:22 INFO - no previously-included directories found matching '.git*' 08:47:22 INFO - Installing collected packages: jsonschema, functools32 08:47:22 INFO - Running setup.py install for functools32 08:47:22 INFO - warning: no files found matching '*.txt' 08:47:22 INFO - no previously-included directories found matching 'build' 08:47:22 INFO - no previously-included directories found matching 'dist' 08:47:22 INFO - no previously-included directories found matching '.git*' 08:47:22 INFO - Successfully installed jsonschema functools32 08:47:22 INFO - Cleaning up... 08:47:22 INFO - Return code: 0 08:47:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:47: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')]} 08:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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')]} 08:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:22 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:22 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 08:47:22 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 08:47:22 INFO - Using env: {'DISPLAY': ':0', 08:47:22 INFO - 'HOME': '/home/cltbld', 08:47:22 INFO - 'LANG': 'en_US.UTF-8', 08:47:22 INFO - 'LANGUAGE': 'en_US:en', 08:47:22 INFO - 'LOGNAME': 'cltbld', 08:47:22 INFO - 'MAIL': '/var/mail/cltbld', 08:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:22 INFO - 'MOZ_NO_REMOTE': '1', 08:47:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:22 INFO - 'NO_EM_RESTART': '1', 08:47:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:22 INFO - 'PWD': '/builds/slave/test', 08:47:22 INFO - 'SHELL': '/bin/bash', 08:47:22 INFO - 'SHLVL': '1', 08:47:22 INFO - 'TERM': 'linux', 08:47:22 INFO - 'TMOUT': '86400', 08:47:22 INFO - 'USER': 'cltbld', 08:47:22 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:22 INFO - '_': '/tools/buildbot/bin/python'} 08:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:47:22 INFO - Cleaning up... 08:47:22 INFO - Return code: 0 08:47:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:47: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')]} 08:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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')]} 08:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:22 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:22 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 08:47:22 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 08:47:22 INFO - Using env: {'DISPLAY': ':0', 08:47:22 INFO - 'HOME': '/home/cltbld', 08:47:22 INFO - 'LANG': 'en_US.UTF-8', 08:47:22 INFO - 'LANGUAGE': 'en_US:en', 08:47:22 INFO - 'LOGNAME': 'cltbld', 08:47:22 INFO - 'MAIL': '/var/mail/cltbld', 08:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:22 INFO - 'MOZ_NO_REMOTE': '1', 08:47:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:22 INFO - 'NO_EM_RESTART': '1', 08:47:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:22 INFO - 'PWD': '/builds/slave/test', 08:47:22 INFO - 'SHELL': '/bin/bash', 08:47:22 INFO - 'SHLVL': '1', 08:47:22 INFO - 'TERM': 'linux', 08:47:22 INFO - 'TMOUT': '86400', 08:47:22 INFO - 'USER': 'cltbld', 08:47:22 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:22 INFO - '_': '/tools/buildbot/bin/python'} 08:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:23 INFO - Downloading/unpacking blobuploader==1.2.4 08:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:25 INFO - Downloading blobuploader-1.2.4.tar.gz 08:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:47:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:47:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 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 08:47:25 INFO - Downloading docopt-0.6.1.tar.gz 08:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:47:25 INFO - Installing collected packages: blobuploader, requests, docopt 08:47:25 INFO - Running setup.py install for blobuploader 08:47:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:47:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:47:25 INFO - Running setup.py install for requests 08:47:26 INFO - Running setup.py install for docopt 08:47:26 INFO - Successfully installed blobuploader requests docopt 08:47:26 INFO - Cleaning up... 08:47:26 INFO - Return code: 0 08:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:26 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:26 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 08:47:26 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 08:47:26 INFO - Using env: {'DISPLAY': ':0', 08:47:26 INFO - 'HOME': '/home/cltbld', 08:47:26 INFO - 'LANG': 'en_US.UTF-8', 08:47:26 INFO - 'LANGUAGE': 'en_US:en', 08:47:26 INFO - 'LOGNAME': 'cltbld', 08:47:26 INFO - 'MAIL': '/var/mail/cltbld', 08:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:26 INFO - 'MOZ_NO_REMOTE': '1', 08:47:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:26 INFO - 'NO_EM_RESTART': '1', 08:47:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:26 INFO - 'PWD': '/builds/slave/test', 08:47:26 INFO - 'SHELL': '/bin/bash', 08:47:26 INFO - 'SHLVL': '1', 08:47:26 INFO - 'TERM': 'linux', 08:47:26 INFO - 'TMOUT': '86400', 08:47:26 INFO - 'USER': 'cltbld', 08:47:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:26 INFO - '_': '/tools/buildbot/bin/python'} 08:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:26 INFO - Running setup.py (path:/tmp/pip-lqvWyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:26 INFO - Running setup.py (path:/tmp/pip-KJH5WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:26 INFO - Running setup.py (path:/tmp/pip-vUgniK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:26 INFO - Running setup.py (path:/tmp/pip-X_sjqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:27 INFO - Running setup.py (path:/tmp/pip-dzLMvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:27 INFO - Running setup.py (path:/tmp/pip-X_YpSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:27 INFO - Running setup.py (path:/tmp/pip-G9RCho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:27 INFO - Running setup.py (path:/tmp/pip-I3i3_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:27 INFO - Running setup.py (path:/tmp/pip-0fd1Hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:27 INFO - Running setup.py (path:/tmp/pip-T6yfv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:27 INFO - Running setup.py (path:/tmp/pip-103lvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:27 INFO - Running setup.py (path:/tmp/pip-1a4bZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:27 INFO - Running setup.py (path:/tmp/pip-0Q0qUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:27 INFO - Running setup.py (path:/tmp/pip-jNqWJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:27 INFO - Running setup.py (path:/tmp/pip-Iysp3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:27 INFO - Running setup.py (path:/tmp/pip-tDUMlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:27 INFO - Running setup.py (path:/tmp/pip-i2vece-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:47:27 INFO - Running setup.py install for manifestparser 08:47:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozcrash 08:47:28 INFO - Running setup.py install for mozdebug 08:47:28 INFO - Running setup.py install for mozdevice 08:47:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozfile 08:47:28 INFO - Running setup.py install for mozhttpd 08:47:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozinfo 08:47:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozInstall 08:47:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozleak 08:47:28 INFO - Running setup.py install for mozlog 08:47:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for moznetwork 08:47:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Running setup.py install for mozprocess 08:47:29 INFO - Running setup.py install for mozprofile 08:47:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Running setup.py install for mozrunner 08:47:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Running setup.py install for mozscreenshot 08:47:29 INFO - Running setup.py install for moztest 08:47:29 INFO - Running setup.py install for mozversion 08:47:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:47:29 INFO - Cleaning up... 08:47:29 INFO - Return code: 0 08:47:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:29 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:29 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 08:47:29 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 08:47:29 INFO - Using env: {'DISPLAY': ':0', 08:47:29 INFO - 'HOME': '/home/cltbld', 08:47:29 INFO - 'LANG': 'en_US.UTF-8', 08:47:29 INFO - 'LANGUAGE': 'en_US:en', 08:47:29 INFO - 'LOGNAME': 'cltbld', 08:47:29 INFO - 'MAIL': '/var/mail/cltbld', 08:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:29 INFO - 'MOZ_NO_REMOTE': '1', 08:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:29 INFO - 'NO_EM_RESTART': '1', 08:47:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:29 INFO - 'PWD': '/builds/slave/test', 08:47:29 INFO - 'SHELL': '/bin/bash', 08:47:29 INFO - 'SHLVL': '1', 08:47:29 INFO - 'TERM': 'linux', 08:47:29 INFO - 'TMOUT': '86400', 08:47:29 INFO - 'USER': 'cltbld', 08:47:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:29 INFO - '_': '/tools/buildbot/bin/python'} 08:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:29 INFO - Running setup.py (path:/tmp/pip-79V1TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:30 INFO - Running setup.py (path:/tmp/pip-EQmGgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:30 INFO - Running setup.py (path:/tmp/pip-YmWoxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:30 INFO - Running setup.py (path:/tmp/pip-agDxTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:30 INFO - Running setup.py (path:/tmp/pip-bwXtGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:30 INFO - Running setup.py (path:/tmp/pip-yHylOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:30 INFO - Running setup.py (path:/tmp/pip-2__ur1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:30 INFO - Running setup.py (path:/tmp/pip-DwNSBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:30 INFO - Running setup.py (path:/tmp/pip-P_1lv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:30 INFO - Running setup.py (path:/tmp/pip-4RCaNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:30 INFO - Running setup.py (path:/tmp/pip-XFGTcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:30 INFO - Running setup.py (path:/tmp/pip-MKPtrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:30 INFO - Running setup.py (path:/tmp/pip-ITAdGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:30 INFO - Running setup.py (path:/tmp/pip-v0IJuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:31 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)) 08:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:31 INFO - Running setup.py (path:/tmp/pip-nGKV5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:31 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)) 08:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:31 INFO - Running setup.py (path:/tmp/pip-2LLGeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:31 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)) 08:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:31 INFO - Running setup.py (path:/tmp/pip-B77gbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:31 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)) 08:47:31 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)) 08:47:31 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)) 08:47:31 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)) 08:47:31 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)) 08:47:31 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)) 08:47:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:34 INFO - Downloading blessings-1.6.tar.gz 08:47:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:47:34 INFO - Installing collected packages: blessings 08:47:34 INFO - Running setup.py install for blessings 08:47:34 INFO - Successfully installed blessings 08:47:34 INFO - Cleaning up... 08:47:34 INFO - Return code: 0 08:47:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:34 INFO - Reading from file tmpfile_stdout 08:47:34 INFO - Current package versions: 08:47:34 INFO - argparse == 1.2.1 08:47:34 INFO - blessings == 1.6 08:47:34 INFO - blobuploader == 1.2.4 08:47:34 INFO - docopt == 0.6.1 08:47:34 INFO - functools32 == 3.2.3-2 08:47:34 INFO - jsonschema == 2.5.1 08:47:34 INFO - manifestparser == 1.1 08:47:34 INFO - mozInstall == 1.12 08:47:34 INFO - mozcrash == 1.0 08:47:34 INFO - mozdebug == 0.1 08:47:34 INFO - mozdevice == 0.49 08:47:34 INFO - mozfile == 1.2 08:47:34 INFO - mozhttpd == 0.7 08:47:34 INFO - mozinfo == 0.9 08:47:34 INFO - mozleak == 0.1 08:47:34 INFO - mozlog == 3.4 08:47:34 INFO - moznetwork == 0.27 08:47:34 INFO - mozprocess == 0.24 08:47:34 INFO - mozprofile == 0.28 08:47:34 INFO - mozrunner == 6.13 08:47:34 INFO - mozscreenshot == 0.1 08:47:34 INFO - mozsystemmonitor == 0.3 08:47:34 INFO - moztest == 0.8 08:47:34 INFO - mozversion == 1.4 08:47:34 INFO - psutil == 3.1.1 08:47:34 INFO - requests == 1.2.3 08:47:34 INFO - wsgiref == 0.1.2 08:47:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 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 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:34 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 08:47:34 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 08:47:34 INFO - Using env: {'DISPLAY': ':0', 08:47:34 INFO - 'HOME': '/home/cltbld', 08:47:34 INFO - 'LANG': 'en_US.UTF-8', 08:47:34 INFO - 'LANGUAGE': 'en_US:en', 08:47:34 INFO - 'LOGNAME': 'cltbld', 08:47:34 INFO - 'MAIL': '/var/mail/cltbld', 08:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:34 INFO - 'MOZ_NO_REMOTE': '1', 08:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:34 INFO - 'NO_EM_RESTART': '1', 08:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:34 INFO - 'PWD': '/builds/slave/test', 08:47:34 INFO - 'SHELL': '/bin/bash', 08:47:34 INFO - 'SHLVL': '1', 08:47:34 INFO - 'TERM': 'linux', 08:47:34 INFO - 'TMOUT': '86400', 08:47:34 INFO - 'USER': 'cltbld', 08:47:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:34 INFO - '_': '/tools/buildbot/bin/python'} 08:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 INFO - Cleaning up... 08:47:34 INFO - Return code: 0 08:47:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', '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 08:47:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:47:34 INFO - Using env: {'DISPLAY': ':0', 08:47:34 INFO - 'HOME': '/home/cltbld', 08:47:34 INFO - 'LANG': 'en_US.UTF-8', 08:47:34 INFO - 'LANGUAGE': 'en_US:en', 08:47:34 INFO - 'LOGNAME': 'cltbld', 08:47:34 INFO - 'MAIL': '/var/mail/cltbld', 08:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:34 INFO - 'MOZ_NO_REMOTE': '1', 08:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:34 INFO - 'NO_EM_RESTART': '1', 08:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:34 INFO - 'PWD': '/builds/slave/test', 08:47:34 INFO - 'SHELL': '/bin/bash', 08:47:34 INFO - 'SHLVL': '1', 08:47:34 INFO - 'TERM': 'linux', 08:47:34 INFO - 'TMOUT': '86400', 08:47:34 INFO - 'USER': 'cltbld', 08:47:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:34 INFO - '_': '/tools/buildbot/bin/python'} 08:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:47:35 INFO - Cleaning up... 08:47:35 INFO - Return code: 0 08:47:35 INFO - Running post-action listener: _resource_record_post_action 08:47:35 INFO - Running post-action listener: _start_resource_monitoring 08:47:35 INFO - Starting resource monitoring. 08:47:35 INFO - [mozharness: 2017-02-09 16:47:35.197160Z] Finished create-virtualenv step (success) 08:47:35 INFO - [mozharness: 2017-02-09 16:47:35.197684Z] Running install step. 08:47:35 INFO - Running pre-action listener: _resource_record_pre_action 08:47:35 INFO - Running main action method: install 08:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:35 INFO - Reading from file tmpfile_stdout 08:47:35 INFO - Detecting whether we're running mozinstall >=1.0... 08:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:47:35 INFO - Reading from file tmpfile_stdout 08:47:35 INFO - Output received: 08:47:35 INFO - Usage: mozinstall [options] installer 08:47:35 INFO - Options: 08:47:35 INFO - -h, --help show this help message and exit 08:47:35 INFO - -d DEST, --destination=DEST 08:47:35 INFO - Directory to install application into. [default: 08:47:35 INFO - "/builds/slave/test"] 08:47:35 INFO - --app=APP Application being installed. [default: firefox] 08:47:35 INFO - mkdir: /builds/slave/test/build/application 08:47:35 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'] 08:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:47:51 INFO - Reading from file tmpfile_stdout 08:47:51 INFO - Output received: 08:47:51 INFO - /builds/slave/test/build/application/firefox/firefox 08:47:51 INFO - Running post-action listener: _resource_record_post_action 08:47:51 INFO - [mozharness: 2017-02-09 16:47:51.983876Z] Finished install step (success) 08:47:51 INFO - [mozharness: 2017-02-09 16:47:51.984154Z] Running run-tests step. 08:47:51 INFO - Running pre-action listener: _resource_record_pre_action 08:47:51 INFO - Running main action method: run_tests 08:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:51 INFO - Python 2.7.3 08:47:51 INFO - Return code: 0 08:47:51 INFO - grabbing minidump binary from tooltool 08:47: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')]} 08:47:51 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 0x175bd00>, '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 0x175ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x175ceb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:51 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 08:47:51 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 08:47:51 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 08:47:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:52 INFO - Return code: 0 08:47:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:47:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:47:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:47:52 INFO - Using env: {'DISPLAY': ':0', 08:47:52 INFO - 'HOME': '/home/cltbld', 08:47:52 INFO - 'LANG': 'en_US.UTF-8', 08:47:52 INFO - 'LANGUAGE': 'en_US:en', 08:47:52 INFO - 'LOGNAME': 'cltbld', 08:47:52 INFO - 'MAIL': '/var/mail/cltbld', 08:47:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:52 INFO - 'MOZ_NO_REMOTE': '1', 08:47:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:52 INFO - 'NO_EM_RESTART': '1', 08:47:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:52 INFO - 'PWD': '/builds/slave/test', 08:47:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:52 INFO - 'SHELL': '/bin/bash', 08:47:52 INFO - 'SHLVL': '1', 08:47:52 INFO - 'TERM': 'linux', 08:47:52 INFO - 'TMOUT': '86400', 08:47:52 INFO - 'USER': 'cltbld', 08:47:52 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359', 08:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:52 INFO - '_': '/tools/buildbot/bin/python'} 08:47:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:47:52 INFO - mozversion application_buildid: 20170209155318 08:47:52 INFO - mozversion application_changeset: 1c204002b9bedaa9fb386dda2031e32d664d6871 08:47:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:47:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:52 INFO - mozversion application_name: Firefox 08:47:52 INFO - mozversion application_remotingname: firefox-dev 08:47:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:52 INFO - mozversion application_vendor: Mozilla 08:47:52 INFO - mozversion application_version: 53.0a2 08:47:52 INFO - mozversion platform_buildid: 20170209155318 08:47:52 INFO - mozversion platform_changeset: 1c204002b9bedaa9fb386dda2031e32d664d6871 08:47:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:52 INFO - mozversion platform_version: 53.0a2 08:47:52 INFO - using testdate: 1486658872 08:47:52 INFO - actual date: 1486658872 08:47:52 INFO - starting webserver on 'localhost:59504' 08:47:52 INFO - SUITE-START | Running 2 tests 08:47:52 INFO - TEST-START | dromaeo_css 08:47:52 INFO - operating with platform_type : linux_ 08:47:52 INFO - Initialising browser for dromaeo_css test... 08:47:52 INFO - TEST-INFO | started process 17706 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZvyPm6/profile http://localhost:59504/getInfo.html) 08:47:56 INFO - PROCESS | 17706 | __metrics Screen width/height:1600/1200 08:47:56 INFO - PROCESS | 17706 | colorDepth:24 08:47:56 INFO - PROCESS | 17706 | Browser inner width/height: 1024/697 08:47:56 INFO - PROCESS | 17706 | __metrics 08:47:56 INFO - PROCESS | 17706 | 1486658876922 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:47:57 INFO - PROCESS | 17706 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:47:57 INFO - PROCESS | 17706 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:47:57 INFO - TEST-INFO | 17706: exit 0 08:47:57 INFO - Browser initialized. 08:47:57 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:57 INFO - TEST-INFO | started process 17798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZvyPm6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:09 INFO - PROCESS | 17798 | RSS: Main: 138051584 08:48:09 INFO - PROCESS | 17798 | 08:51:09 INFO - PROCESS | 17798 | Cycle 1(1): loaded http://localhost:59504/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59504/tests/dromaeo/cssquery-ext.html) 08:51:11 INFO - PROCESS | 17798 | RSS: Main: 259006464 08:51:11 INFO - PROCESS | 17798 | 08:52:59 INFO - PROCESS | 17798 | ************************* 08:52:59 INFO - PROCESS | 17798 | A coding exception was thrown and uncaught in a Task. 08:52:59 INFO - PROCESS | 17798 | 08:52:59 ERROR - PROCESS | 17798 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:52:59 INFO - PROCESS | 17798 | Full stack: 08:52:59 INFO - PROCESS | 17798 | ************************* 08:54:13 INFO - PROCESS | 17798 | Cycle 1(1): loaded http://localhost:59504/tests/dromaeo/cssquery-ext.html (next: http://localhost:59504/tests/dromaeo/cssquery-jquery.html) 08:54:14 INFO - PROCESS | 17798 | RSS: Main: 222040064 08:54:14 INFO - PROCESS | 17798 | 08:57:15 INFO - PROCESS | 17798 | Cycle 1(1): loaded http://localhost:59504/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59504/tests/dromaeo/cssquery-mootools.html) 08:57:16 INFO - PROCESS | 17798 | RSS: Main: 229662720 08:57:16 INFO - PROCESS | 17798 | 09:00:18 INFO - PROCESS | 17798 | Cycle 1(1): loaded http://localhost:59504/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59504/tests/dromaeo/cssquery-prototype.html) 09:00:18 INFO - PROCESS | 17798 | RSS: Main: 221093888 09:00:18 INFO - PROCESS | 17798 | 09:03:21 INFO - PROCESS | 17798 | Cycle 1(1): loaded http://localhost:59504/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59504/tests/dromaeo/cssquery-yui.html) 09:03:22 INFO - PROCESS | 17798 | RSS: Main: 174493696 09:03:22 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | Cycle 1(1): loaded http://localhost:59504/tests/dromaeo/cssquery-yui.html (next: http://localhost:59504/tests/dromaeo/cssquery-dojo.html) 09:06:24 INFO - PROCESS | 17798 | RSS: Main: 254758912 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | __start_tp_report 09:06:24 INFO - PROCESS | 17798 | _x_x_mozilla_page_load 09:06:24 INFO - PROCESS | 17798 | _x_x_mozilla_page_load_details 09:06:24 INFO - PROCESS | 17798 | |i|pagename|runs| 09:06:24 INFO - PROCESS | 17798 | |0;dojo.html;3305.1076209423454;3316.2206881382886;3322.053214833773;3335.099523128755;3375.848086836092;5454.127339625661;5493.313335833021;5573.08044172711;5586;5600.52395546379;5474.151506516487;5527.778888844441;5534.08687566551;5534.667919924807;5576.665400075988;4926.679765815219;4943.406791185045;4967.850964471054;5007.774650140735;5012.72430016349;4876.853694389156;4892.6575139740235;4902.289168070628;4909.386326709161;4984.775685094161;5005.699658020512;5020.39755229373;5033.899322013576;5057.317262169615;5059.974700126494;4995.125852975715;5023.296738456619;5025.673331233458;5046.369203849537;5058.190689489682;4816.54242846929;4832.226843705025;4857.854264372039;4866.975665121652;4966.726830024347;5385.838424847261;5387.084195686703;5397.4062853086125;5401.675899446046;5447.918281225785;5862.912056319158;5873.471387575096;5876.9118463223085;5877.089051197071;5884.941150588482;5706.914396284059;5734.254546908917;5865.472107510344;5910.290765108171;5910.468057874769;5471.835844924659;5536.390996990328;5545.112781954868;5574.86062848428;5577.581681373913;6013.609115407485;6016.518678505709;6018.638881667114;6027.126066720301;6035.366286539938;5696.544276457874;5733.054046082433;5830.650160990354;5831.504322132581;5831.708414579254;5819.185314056035;5826.650400975955;5872.677002764889;5928.110783382441;5963.701814909237;5814.883702325929;5885.823425297248;5894.823155305347;5905.852353691193;5906.704664766745;5653.886922261532;5908.350081491032;5918.201042859245;5920.970395147997;5925.466707996215;4528.230200865795;4564.954350456519;4589.33454649074;4615.053913947632;4619.076184762994;4066.4103704962613;4072.531658859269;4075.653569446571;4081.836726530965;4095.877123686294;5670.943290567042;5718.085106382959;5755.654660720286;5760.596758226884;5789.421057894177;5823.242978412779;5827.708614569255;5859.736311865976;5890.764369425261;5953.880922381615;5715.142728590745;5720.370759216483;5722.77108915647;5728.255326807572;5730.283714535683;4963.6277279204205;5055.772490237948;5063.822766203165;5066.721330326867;5100.362454693163;6054.3945605439985;6123.069106197231;6160.599561028519;6162.568620196633;6278.968605156945;5956.76172953077;5988.984173464352;6012.428819262163;6013.158157857991;6049.546284028627;6267.185265915402;6294.905576416449;6297.992321228581;6304.89664308735;6307.936920630918;5734.311882574118;5766.308043034863;5790.478856902899;5867.178594996789;5884.823455296348;6019.608725432833;6025.969870150621;6029.3669164737075;6030.939690603038;6048.788292409745;5891.499222565958;5893.292804863444;5894.0274854648505;5895.145203945318;5896.204012458264;5587.189857470726;5698.202251684685;5715.971420142872;5733.741981610921;5865.032269675456;5802.4197580241635;5806.912896306474;5812.82561523155;5822.737976791139;5844.12338149281;5783.94216057834;5836.299644042572;5839.532837372915;5841.036229022164;5846.824595262149;5514.145307477347;5529.474699903502;5639.887202256014;5676.091825307931;5682.715864206691 09:06:24 INFO - PROCESS | 17798 | |1;ext.html;557.9274694289742;601.4376557918346;623.224086012914;628.1739512541009;642.8135840606225;36760.81619591902;45989.77005114974;47791.522084779186;47920;47939.760301198534;51528.48471515284;51808.74095629526;52014.73992630032;52056.47943520573;52080;8833;9339.15947564719;9528.904710952906;9538.90461095387;9538.952305238465;9099.181073703365;10755.408452535108;11089.83365249522;11112.333260004385;11507.482163302671;11400.030997365235;11423.885761142365;11492.080633549296;11495.19533632646;11518.596849110283;11424.200305978586;11450.541978320856;11473.65579032622;11483.081353491702;11492.253003554742;11259.1555633327;11335;11338.263012904135;11339.489722962486;11346.546138154546;47195;47299.76350118262;47546.5245347549;47580;47635.285470717965;4314.71954322968;4422.491413487426;4524.977375113103;4537.205988951953;4702.106599746037;32164.678353216637;32376.838115809507;32383.352332953207;32426.837865810758;33330.666693333;4570.223062079422;4617.8152873885;4672.299155126758;4684.8360307389075;4778.546038126407;371346;371818;372473;372526;372604;59509.702451487465;59797.701011495534;59846.70076649676;59872.401275987555;59977.70011150004;62520.687396562724;62553.374466254754;62614;62711.372886270554;62771.37228627747;17337.82662173362;17573.824261757476;17615.207315670825;17624.55938601545;17633.7354939676;12547.372631368395;12605.558805441766;12633.93683031579;12795;13221.206727596182;18753.34363297278;19422.28007591622;19549.404827235347;19573.719885912364;19638.803611963984;7238.746643867439;7309.415246780245;7518.210587888193;7585.734499292498;7833.569153696488;934.5794392523392;938.9953050234706;940.9294302927171;947.2564037230741;952.7808604020976;31768;32610;33171.336573268396;33353.49969750456;33796.15509612279;997.3892164154838;1051.7002654243656;1111.2776695148218;1118.4799068433217;1136.676047326508;16709.6658066838;17609.085475194464;17747;17783.73324400135;17899.19453624564;531010;533946;539127;541380;541773;70691;70803.64598176976;70842.64578677074;70977;71403.64298178475;1553.937842486288;1567.3495499367862;1577.5582836805484;1593.6971975324652;1599.128474981138;34772.478412824334;35839.64160358467;36046.09884752902;36154.27691446209;36167.27665446729;60271.39728602658;62350;62445.06332404927;62460;62474.37525624686;486146;489336;489363;491402;493119;42308;43092.56907431011;46000;48473;50213.74893125511;50027.749861250464;53561.19658205052;54006;54251.72874135604;55560;43157.784211078746;43621.781891090344;45223.547764521936;45911;46588.30117548172;40997.18005639811;41716;41728.37407438952;42656.78671606622;42702.359464607434;43818.56181438145;43842.56157438385;43894.34158487561;44271;44460.55539444564;37025;39359;39477.802610986764;39594.80202599084;39952.20095598014;29267.853660731562;29342;29757.577878544245;29782.404351912406;29797 09:06:24 INFO - PROCESS | 17798 | |2;jquery.html;436.16256786969024;440.5660424481883;444.50424322155936;447.0544797752754;450.7137967390706;19647.60704785905;21171.259005934775;21646.89176554119;21772.237971670995;21940.45148871277;20730;22206.333809985685;22246.55506889865;22255.888720556373;22413.66379504309;5920.378360272174;5936.64380137191;6162.22972128484;6165.445109940105;6168.568200225986;5982.8504287392725;6046.697665116749;6090.056041313604;6096.695165241742;6096.908546371808;6024.337322894478;6136.662483563402;6144.723487443053;6147.385261473862;6214.78811631732;6336.58812177207;6359.523035772289;6370.076338930852;6371.968140159316;6373.681315934208;6122.295935967376;6213.099100630383;6251.062340648894;6256.124142619991;6260.499160067184;15366.769498457532;15629.218539073001;23010.76989230103;25358.746412535824;25795.74204257952;3143.2141964508874;3148.7481001519823;3179.8728050877935;3216.662250463691;3234.126785767853;10906.291091079134;11147.498362573704;11324.377159256237;11345.602903898407;11408.315501069963;2021.88227139958;2024.3623258673472;2026.1186383922893;2031.8476114291486;2052.7639321478073;96560;96753.51623241839;97614;98029;98692.50653746685;12204.694882628006;12293.139480236297;12317.753644927052;12602.306873122066;12650.620481385573;17716.557086072953;17874.374396896048;17887;17904;17919.193636286138;8958.417702849294;9009.324300677476;9073.954630226806;9121.45271283725;9147.542622868963;8444.408891377545;8526.445781024213;8526.87209691855;8741.388102832869;8760;5165.431802501722;5230.581553475713;5238.73806309683;5256.947430525723;5548.5838562108;2097.1820989813987;2129.169623846701;2150.827933765295;2211.668249762516;2214.9446263843533;878.2271600991214;893.1515060692368;897.3942588752697;901.3150005995491;917.6879860847343;19411;19944.20223191056;19973.800261997196;20024.799752002295;20465.79534204639;911.49867572836;913.4199783137756;916.7983043730369;919.1038737231146;923.4828496042238;10751.139908807276;11148.386838723785;11196.328220306645;11263.60577379804;11367.94316028431;663860;693395;695154;705805;723539;97978;114471;115088;115701.42149289369;116815.8318416822;1063.9148868090533;1078.4122653153893;1083.7778255457613;1101.1081024370285;1106.269861891148;17611.38360157388;17799.377021804175;18146.18342174579;18166.72749908726;18486.722699159254;43034.35448468213;45634.771826140655;46133.538664614265;46611.30083048689;48090.27864581964;56824.431755681915;66332.33667663262;67239.32760672331;67370.66314668395;67850.66074669595;9461.526923653928;9856.507174641383;9881.357711748713;9990.100890919852;9998.600055997678;14942.178180199759;14963.551093467213;14990;15321.846781532042;15561;12145.14983951115;12432.440540175532;12717.300548469922;12889.291088990196;13072.542461013818;11835.408229588658;11982.64052078439;12165.939170304091;12354.505819767108;12640.620781376205;12032.939835300767;12228.571999979958;12455.626331210078;12461;12657;9568.4737339447;9625.32622716403;9663.903360966582;9743.853842192515;9815.803683926424;11258.887411125785;11752.412379380825;11798.469068891749;11841.348725820162;11886 09:06:24 INFO - PROCESS | 17798 | |3;mootools.html;1344.784834426492;1456.7159403916235;1483.3769816676995;1487.7693957436595;1487.8586534279239;7826;8254.711085112021;8318.085010648823;8521.786955326112;8810.07494213108;8916.331275154373;8973.641054357819;8984.595693193822;9008.864867027;9026.90973090269;2140.090461553841;2206.9448263793374;2386.9761302387014;2429.878506074698;2430.6232533957177;2366.4557151855083;2456.828022038458;2457.9262622121278;2479.4917042006437;2480.615504596781;2327.7090363704538;2328.6390609455475;2334.6498025296255;2351.1535847095033;2363.869987150706;2399.076355603088;2414.8188885833547;2423.018677435641;2425.6482809992453;2437.122635851092;2340.2277248507953;2352.776486233805;2354.9646755298686;2358.5518751437344;2359.7050368703913;9027.277817774562;9050.411723238034;9110.5444727764;9121.863172052424;9128.543572821332;5280.075986702312;5379.300690910196;5421.430749771296;5425.647332923348;5428.131498960148;3350.3131857969056;3493.615702272748;3553.5558055243096;3597.388443964532;3670.614585468514;1372.5813626843808;1482.2366481262159;1525.3898440623727;1529.4646873594265;1554.7123782100234;10932.890671093266;11109.555617775279;11204.15968802343;11762.059035277158;11813.114016448715;4442.955570444255;4616.838410655678;4629.629629629621;4680.321353403738;4689.95310046902;9424.717258482257;9449.244060475145;9455.669051583161;9466.384684995592;9488.335816492918;5543.417941116206;5547.389787123413;5548.250986116904;5548.4174161713245;5552.611317207757;4311.417958575552;4967.7019378837385;4975.751212439436;5081.161608334583;5135.6918584885025;3853.4412510185866;3947.348687466593;3976.5029371328583;4009.8195581198916;4028.5568587455355;1213.1023042948332;1225.9509619615294;1226.3316492511603;1232.4885172653428;1239.783037968352;969.1616751510035;986.4229425785852;990.1435258501344;996.8834904906406;1007.959681612742;5641.887162256732;5672.319321681424;5704.8859022819315;5762.164486149569;5764.827055188351;589.8407429993878;606.5390303369383;615.4553220399978;623.7598524567983;626.2422468812697;3878.108035151931;3913.5303763547986;3941.0147463134217;4109.876703698894;4121.608447197512;10345.37927724339;10912.61805836792;10935.945320273348;10945.562177512811;10946.288491248044;10019.649312274036;10326.380417174843;10781.137508999262;10866.424368466529;10904.400257985886;8052.879206811903;8167.060788009455;8168.42821002536;8208.179182081743;8209.425340226127;5539.8892022160135;6051.183090282755;6074.210352654127;6142.815715528542;6190.7214175363115;9200.95399522998;10107.7473063174;10126.797464050826;10135.493225338556;10152.543135559064;9701;9878.506074696093;9905.603775849173;9969.252306077218;9996.950015249877;7264.382527485011;7269;7615.885761713689;7831.686732530635;8004.719834805754;8622.26710729595;8773.736787896374;8786.516741579275;8796.868046979429;8804.867926980973;7738.380929525737;7738.4969976951315;7754.263344982218;7825.843483130192;7827.92172078272;7659.195784442776;7714.15144334134;7716.961415193113;7810.218978102144;7903.486273392211;6688.799336019927;6724.6886857064355;6785.18577770651;6790.96604516994;6810.5913645181445;4959.752012399294;5061.114304996685;5089.618278629044;5167.948320516897;5461.180822876441;5247.449017853223;5290.523852853108;5342.278792362981;5354.518093371616;5374.623776335619 09:06:24 INFO - PROCESS | 17798 | |4;prototype.html;400.35143068229473;412.1386302627508;414.08280658341766;425.5829287298449;427.0966904995935;5252.71110088945;5330.28041214436;5342.691040695036;5369.489898459645;5391.005328028625;5060.671056381342;5090.338256026711;5170.741462926858;5191.818286359978;5270.9736451317685;4260.573942605738;4260.8721738347895;4267.679924005696;4275.8075886585175;4276.550962148976;4243.896586887416;4248.4052232687445;4283.764392958386;4291.8497852575165;4317.71934824237;4131.669466442678;4186.706930514865;4214.97892510537;4216.177845320164;4223.324268117102;4192.769397683126;4204.306289462265;4213.789310534492;4215.725977811432;4224.936625950613;3847.1343947611845;3861.5905194603765;3864.5555761087553;3868.129670824042;3868.1490072184356;4998;5101.20931255656;5122.5133612307145;5190.92213616796;5199;4837.467878533358;4869.5130486951375;4876.414830220361;4895.657303988723;4914.533119353656;5545.556355491551;5548.167774833767;5559.6664200148125;5572.804951826707;5582.162675598652;4212.473440819897;4244.681648876347;4261.272785417335;4296.2696341621995;4324.113556720896;5672.290963629546;5693.601447898649;5704.572157088193;5707.401927460276;5743.386759024634;4395.450568678915;4409.823607055682;4427.424434823452;4432.268675668543;4453.75504347264;4313.331433627793;4452.508409682792;4483.327500874895;4523.909521809611;4533.863984080483;4947.925781113214;4966.6771659842;4990.226514890198;5001.24981252815;5004.574611158092;5641.70240844608;5656.472752356923;5677.971610142005;5715.313982375209;5716;1438.3239877257672;1479.9112053276622;1483.7922690823293;1508.351408894186;1540.7688846673088;1045.7072019834459;1055.5883205549842;1081.9342947197006;1089.1831126655009;1091.4106382553332;5280.762365693553;5301.390340110936;5310.283111779938;5315.681059136464;5328.6802791832615;5483.890322193533;5491.940522304136;5494.752736126884;5496.81818409044;5506.448340820229;5084.593232541388;5272.947270527322;5319.255304257407;5322.494363035506;5338.3060202173465;2879.8560071996317;2886.422715456917;2907.680155182928;2924.8537573121685;2925.7659387249396;5699.658020518782;5707.914381284284;5720.885582288331;5721.5708821838525;5751.281089863767;5345.786168553214;5555.222388805597;5575.609707320448;5623.268975033219;5646.632968857011;5736.569757268222;5748.798792042342;5768.779020077217;5776.855578610568;5793.971030144738;5387.515123638891;5484.367503598375;5486.561075113901;5533.335999680065;5562.276904002454;5612.01789686795;5618.269624948731;5627.396192085236;5628.127640215773;5645.71771411436;5933.546281161143;5935.228420305332;5987.251593550806;5987.491063259672;6002.099685047103;4029.106320029581;4145.108801607669;4150.584941505826;4180.9163816722885;4203.369494575838;5699.943000569941;5704.885902282014;5706.372299047184;5710.885782284248;5715;5380.246765452762;5423.4034256231;5489.945100549103;5547.278853749148;5570.582206334622;5495.450454954473;5511.696856672761;5532.529734972572;5545.279113715191;5571.164325351067;5357.973210133924;5502.972485137549;5537.307836520435;5565.805196818092;5577.191307260506;5567.192757050286;5568.415316391883;5568.470995255444;5580.804671836466;5602.747876345493;5418.431064738145;5437.8368648940595;5455.3453585568395;5457.199124289054;5487.176923461513 09:06:24 INFO - PROCESS | 17798 | |5;yui.html;793.9880901786478;810.939179561533;825.3809642767924;925.9259259259258;934.6632521896829;16731.665366692658;17256.82743172568;17973.730394044083;17982.640347193083;17992.55018624535;16336.918315408437;16592.917035414837;16611.83388166118;16617.83382166178;16622.75065874013;712.2023333866065;724.9673764680575;822.1696086952192;838.4382463749272;839.1273076000982;846.3609974469267;847.2374862623657;847.4152834544176;848.0798333807795;848.4357901995165;888.5512816027897;890.2032680750724;891.5987805487524;891.7993451473399;894.1996912763074;744.4044764188627;759.5177062565283;1092.7049696581958;1138.4421633399575;1141.0928311991986;647.4043879630703;649.0880313160018;649.506375154881;650.0217173154373;651.1697585578378;16007.119608421532;16330.83669163305;17551.122443877754;17581.7362739559;17601;16420.261088251056;16747.413840515524;16795.16024198785;16882.915585421993;17758.733618995724;12902.548410805577;13096.934515327363;13222.669433264246;13266.336683165802;13297.401616927262;4021.718479706422;4043.939340909889;4052.736572122832;4123.835046598132;4130.710850240484;68852.6557367215;75279.24720752778;79573;80440;81213;12236.75526489483;12697.047721420746;12723.23660580368;12755.808662870064;12806.743865122831;23382.883085584803;23385.181518646765;23423.41441463943;23448.06207751705;23846.16538421147;9469.47917864524;9479.431234125837;9622.807543849083;9637.614495420245;9720.416774993382;5068.467810879878;5077.1876499759865;5092.414372347153;5216.686998780086;5232.633715639945;3909.765414075108;4005.0588111793704;4005.619466150711;4028.053407449247;4073.124278280184;648.7859006527834;652.6182183422751;654.5156584127711;666.6500087454028;667.889798183304;794.7734895554739;823.4071468542639;827.3091968206614;831.7296878514506;846.8602680557763;10797.974192451704;11092.33445993243;11268.323900565993;11474.770504589862;11601.825972610417;730.2223132364015;734.4565021883767;747.3050063440943;751.1136858506906;751.4514404484647;4960.503949605083;5249.133892907704;5300.549453296513;5310.6813591184655;5339.679619222781;81662;82426.58786706028;84206.578967106;85384.57307713546;87512.56243718868;71009;71967.64016179886;72223.63888180525;73196;74340.62829685817;64114.358856411774;64614.35385646178;66614;67241.32758672448;68740.6562967192;10122.645707400206;10241;10321.74195645115;10393.740156496147;10478.318909270873;43475;45219.09561808811;46765.53234467748;47688.761556191996;49293.75353123211;53227.4677253238;53493.7325313371;57993.130103047646;57999.4200057994;58109.70945145247;13183.813456788936;13245.801312980506;13640.249786261455;13746.93126534361;13801.309934503435;14323.355449005028;14378;14388.28058597045;14465;14534.927325363305;13754.587362379145;13764.449422023006;13890;13939.860601394263;14533.781993269899;13303.600891973256;13782;13913.234772087633;14044.508442204473;14211.50259740904;12355.836187285435;12423.254604723385;12441.564545240873;12469.75060498803;12474.750504989668;10928.37188847728;11420.371879546705;11572.18994670365;11650.359230242419;11671.35807530594;10825.296355736851;11108.777824443627;11422.828657569977;11441.313521188755;11477.885221148015 09:06:24 INFO - PROCESS | 17798 | __end_tp_report 09:06:24 INFO - PROCESS | 17798 | __start_cc_report 09:06:24 INFO - PROCESS | 17798 | _x_x_mozilla_cycle_collect,1071 09:06:24 INFO - PROCESS | 17798 | __end_cc_report 09:06:24 INFO - PROCESS | 17798 | __startTimestamp1486659984957__endTimestamp 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | ------- Summary: start ------- 09:06:24 INFO - PROCESS | 17798 | Number of tests: 6 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | [#0] dojo.html Cycles:165 Average:5500.33 Median:5730.28 stddev:622.28 (10.9%) stddev-sans-first:599.88 09:06:24 INFO - PROCESS | 17798 | Values: 3305.1 3316.2 3322.1 3335.1 3375.8 5454.1 5493.3 5573.1 5586.0 5600.5 5474.2 5527.8 5534.1 5534.7 5576.7 4926.7 4943.4 4967.9 5007.8 5012.7 4876.9 4892.7 4902.3 4909.4 4984.8 5005.7 5020.4 5033.9 5057.3 5060.0 4995.1 5023.3 5025.7 5046.4 5058.2 4816.5 4832.2 4857.9 4867.0 4966.7 5385.8 5387.1 5397.4 5401.7 5447.9 5862.9 5873.5 5876.9 5877.1 5884.9 5706.9 5734.3 5865.5 5910.3 5910.5 5471.8 5536.4 5545.1 5574.9 5577.6 6013.6 6016.5 6018.6 6027.1 6035.4 5696.5 5733.1 5830.7 5831.5 5831.7 5819.2 5826.7 5872.7 5928.1 5963.7 5814.9 5885.8 5894.8 5905.9 5906.7 5653.9 5908.4 5918.2 5921.0 5925.5 4528.2 4565.0 4589.3 4615.1 4619.1 4066.4 4072.5 4075.7 4081.8 4095.9 5670.9 5718.1 5755.7 5760.6 5789.4 5823.2 5827.7 5859.7 5890.8 5953.9 5715.1 5720.4 5722.8 5728.3 5730.3 4963.6 5055.8 5063.8 5066.7 5100.4 6054.4 6123.1 6160.6 6162.6 6279.0 5956.8 5989.0 6012.4 6013.2 6049.5 6267.2 6294.9 6298.0 6304.9 6307.9 5734.3 5766.3 5790.5 5867.2 5884.8 6019.6 6026.0 6029.4 6030.9 6048.8 5891.5 5893.3 5894.0 5895.1 5896.2 5587.2 5698.2 5716.0 5733.7 5865.0 5802.4 5806.9 5812.8 5822.7 5844.1 5783.9 5836.3 5839.5 5841.0 5846.8 5514.1 5529.5 5639.9 5676.1 5682.7 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | [#1] ext.html Cycles:180 Average:65063.81 Median:43868.45 stddev:124704.43 (284.3%) stddev-sans-first:124960.21 09:06:24 INFO - PROCESS | 17798 | Values: 557.9 601.4 623.2 628.2 642.8 36760.8 45989.8 47791.5 47920.0 47939.8 51528.5 51808.7 52014.7 52056.5 52080.0 8833.0 9339.2 9528.9 9538.9 9539.0 9099.2 10755.4 11089.8 11112.3 11507.5 11400.0 11423.9 11492.1 11495.2 11518.6 11424.2 11450.5 11473.7 11483.1 11492.3 11259.2 11335.0 11338.3 11339.5 11346.5 47195.0 47299.8 47546.5 47580.0 47635.3 4314.7 4422.5 4525.0 4537.2 4702.1 32164.7 32376.8 32383.4 32426.8 33330.7 4570.2 4617.8 4672.3 4684.8 4778.5 371346.0 371818.0 372473.0 372526.0 372604.0 59509.7 59797.7 59846.7 59872.4 59977.7 62520.7 62553.4 62614.0 62711.4 62771.4 17337.8 17573.8 17615.2 17624.6 17633.7 12547.4 12605.6 12633.9 12795.0 13221.2 18753.3 19422.3 19549.4 19573.7 19638.8 7238.7 7309.4 7518.2 7585.7 7833.6 934.6 939.0 940.9 947.3 952.8 31768.0 32610.0 33171.3 33353.5 33796.2 997.4 1051.7 1111.3 1118.5 1136.7 16709.7 17609.1 17747.0 17783.7 17899.2 531010.0 533946.0 539127.0 541380.0 541773.0 70691.0 70803.6 70842.6 70977.0 71403.6 1553.9 1567.3 1577.6 1593.7 1599.1 34772.5 35839.6 36046.1 36154.3 36167.3 60271.4 62350.0 62445.1 62460.0 62474.4 486146.0 489336.0 489363.0 491402.0 493119.0 42308.0 43092.6 46000.0 48473.0 50213.7 50027.7 53561.2 54006.0 54251.7 55560.0 43157.8 43621.8 45223.5 45911.0 46588.3 40997.2 41716.0 41728.4 42656.8 42702.4 43818.6 43842.6 43894.3 44271.0 44460.6 37025.0 39359.0 39477.8 39594.8 39952.2 29267.9 29342.0 29757.6 29782.4 29797.0 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | [#2] jquery.html Cycles:180 Average:36664.60 Median:3164.31 stddev:114534.23 (3619.6%) stddev-sans-first:114823.23 09:06:24 INFO - PROCESS | 17798 | Values: 436.2 440.6 444.5 447.1 450.7 19647.6 21171.3 21646.9 21772.2 21940.5 20730.0 22206.3 22246.6 22255.9 22413.7 5920.4 5936.6 6162.2 6165.4 6168.6 5982.9 6046.7 6090.1 6096.7 6096.9 6024.3 6136.7 6144.7 6147.4 6214.8 6336.6 6359.5 6370.1 6372.0 6373.7 6122.3 6213.1 6251.1 6256.1 6260.5 15366.8 15629.2 23010.8 25358.7 25795.7 3143.2 3148.7 3179.9 3216.7 3234.1 10906.3 11147.5 11324.4 11345.6 11408.3 2021.9 2024.4 2026.1 2031.8 2052.8 96560.0 96753.5 97614.0 98029.0 98692.5 12204.7 12293.1 12317.8 12602.3 12650.6 17716.6 17874.4 17887.0 17904.0 17919.2 8958.4 9009.3 9074.0 9121.5 9147.5 8444.4 8526.4 8526.9 8741.4 8760.0 5165.4 5230.6 5238.7 5256.9 5548.6 2097.2 2129.2 2150.8 2211.7 2214.9 878.2 893.2 897.4 901.3 917.7 19411.0 19944.2 19973.8 20024.8 20465.8 911.5 913.4 916.8 919.1 923.5 10751.1 11148.4 11196.3 11263.6 11367.9 663860.0 693395.0 695154.0 705805.0 723539.0 97978.0 114471.0 115088.0 115701.4 116815.8 1063.9 1078.4 1083.8 1101.1 1106.3 17611.4 17799.4 18146.2 18166.7 18486.7 43034.4 45634.8 46133.5 46611.3 48090.3 56824.4 66332.3 67239.3 67370.7 67850.7 9461.5 9856.5 9881.4 9990.1 9998.6 14942.2 14963.6 14990.0 15321.8 15561.0 12145.1 12432.4 12717.3 12889.3 13072.5 11835.4 11982.6 12165.9 12354.5 12640.6 12032.9 12228.6 12455.6 12461.0 12657.0 9568.5 9625.3 9663.9 9743.9 9815.8 11258.9 11752.4 11798.5 11841.3 11886.0 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | [#3] mootools.html Cycles:180 Average:5751.32 Median:5285.30 stddev:3182.97 (60.2%) stddev-sans-first:3174.67 09:06:24 INFO - PROCESS | 17798 | Values: 1344.8 1456.7 1483.4 1487.8 1487.9 7826.0 8254.7 8318.1 8521.8 8810.1 8916.3 8973.6 8984.6 9008.9 9026.9 2140.1 2206.9 2387.0 2429.9 2430.6 2366.5 2456.8 2457.9 2479.5 2480.6 2327.7 2328.6 2334.6 2351.2 2363.9 2399.1 2414.8 2423.0 2425.6 2437.1 2340.2 2352.8 2355.0 2358.6 2359.7 9027.3 9050.4 9110.5 9121.9 9128.5 5280.1 5379.3 5421.4 5425.6 5428.1 3350.3 3493.6 3553.6 3597.4 3670.6 1372.6 1482.2 1525.4 1529.5 1554.7 10932.9 11109.6 11204.2 11762.1 11813.1 4443.0 4616.8 4629.6 4680.3 4690.0 9424.7 9449.2 9455.7 9466.4 9488.3 5543.4 5547.4 5548.3 5548.4 5552.6 4311.4 4967.7 4975.8 5081.2 5135.7 3853.4 3947.3 3976.5 4009.8 4028.6 1213.1 1226.0 1226.3 1232.5 1239.8 969.2 986.4 990.1 996.9 1008.0 5641.9 5672.3 5704.9 5762.2 5764.8 589.8 606.5 615.5 623.8 626.2 3878.1 3913.5 3941.0 4109.9 4121.6 10345.4 10912.6 10935.9 10945.6 10946.3 10019.6 10326.4 10781.1 10866.4 10904.4 8052.9 8167.1 8168.4 8208.2 8209.4 5539.9 6051.2 6074.2 6142.8 6190.7 9201.0 10107.7 10126.8 10135.5 10152.5 9701.0 9878.5 9905.6 9969.3 9997.0 7264.4 7269.0 7615.9 7831.7 8004.7 8622.3 8773.7 8786.5 8796.9 8804.9 7738.4 7738.5 7754.3 7825.8 7827.9 7659.2 7714.2 7717.0 7810.2 7903.5 6688.8 6724.7 6785.2 6791.0 6810.6 4959.8 5061.1 5089.6 5167.9 5461.2 5247.4 5290.5 5342.3 5354.5 5374.6 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | [#4] prototype.html Cycles:180 Average:4718.44 Median:5312.98 stddev:1321.57 (24.9%) stddev-sans-first:1284.92 09:06:24 INFO - PROCESS | 17798 | Values: 400.4 412.1 414.1 425.6 427.1 5252.7 5330.3 5342.7 5369.5 5391.0 5060.7 5090.3 5170.7 5191.8 5271.0 4260.6 4260.9 4267.7 4275.8 4276.6 4243.9 4248.4 4283.8 4291.8 4317.7 4131.7 4186.7 4215.0 4216.2 4223.3 4192.8 4204.3 4213.8 4215.7 4224.9 3847.1 3861.6 3864.6 3868.1 3868.1 4998.0 5101.2 5122.5 5190.9 5199.0 4837.5 4869.5 4876.4 4895.7 4914.5 5545.6 5548.2 5559.7 5572.8 5582.2 4212.5 4244.7 4261.3 4296.3 4324.1 5672.3 5693.6 5704.6 5707.4 5743.4 4395.5 4409.8 4427.4 4432.3 4453.8 4313.3 4452.5 4483.3 4523.9 4533.9 4947.9 4966.7 4990.2 5001.2 5004.6 5641.7 5656.5 5678.0 5715.3 5716.0 1438.3 1479.9 1483.8 1508.4 1540.8 1045.7 1055.6 1081.9 1089.2 1091.4 5280.8 5301.4 5310.3 5315.7 5328.7 5483.9 5491.9 5494.8 5496.8 5506.4 5084.6 5272.9 5319.3 5322.5 5338.3 2879.9 2886.4 2907.7 2924.9 2925.8 5699.7 5707.9 5720.9 5721.6 5751.3 5345.8 5555.2 5575.6 5623.3 5646.6 5736.6 5748.8 5768.8 5776.9 5794.0 5387.5 5484.4 5486.6 5533.3 5562.3 5612.0 5618.3 5627.4 5628.1 5645.7 5933.5 5935.2 5987.3 5987.5 6002.1 4029.1 4145.1 4150.6 4180.9 4203.4 5699.9 5704.9 5706.4 5710.9 5715.0 5380.2 5423.4 5489.9 5547.3 5570.6 5495.5 5511.7 5532.5 5545.3 5571.2 5358.0 5503.0 5537.3 5565.8 5577.2 5567.2 5568.4 5568.5 5580.8 5602.7 5418.4 5437.8 5455.3 5457.2 5487.2 09:06:24 INFO - PROCESS | 17798 | 09:06:24 INFO - PROCESS | 17798 | [#5] yui.html Cycles:180 Average:18552.40 Median:4098.48 stddev:23223.04 (566.6%) stddev-sans-first:23249.90 09:06:24 INFO - PROCESS | 17798 | Values: 794.0 810.9 825.4 925.9 934.7 16731.7 17256.8 17973.7 17982.6 17992.6 16336.9 16592.9 16611.8 16617.8 16622.8 712.2 725.0 822.2 838.4 839.1 846.4 847.2 847.4 848.1 848.4 888.6 890.2 891.6 891.8 894.2 744.4 759.5 1092.7 1138.4 1141.1 647.4 649.1 649.5 650.0 651.2 16007.1 16330.8 17551.1 17581.7 17601.0 16420.3 16747.4 16795.2 16882.9 17758.7 12902.5 13096.9 13222.7 13266.3 13297.4 4021.7 4043.9 4052.7 4123.8 4130.7 68852.7 75279.2 79573.0 80440.0 81213.0 12236.8 12697.0 12723.2 12755.8 12806.7 23382.9 23385.2 23423.4 23448.1 23846.2 9469.5 9479.4 9622.8 9637.6 9720.4 5068.5 5077.2 5092.4 5216.7 5232.6 3909.8 4005.1 4005.6 4028.1 4073.1 648.8 652.6 654.5 666.7 667.9 794.8 823.4 827.3 831.7 846.9 10798.0 11092.3 11268.3 11474.8 11601.8 730.2 734.5 747.3 751.1 751.5 4960.5 5249.1 5300.5 5310.7 5339.7 81662.0 82426.6 84206.6 85384.6 87512.6 71009.0 71967.6 72223.6 73196.0 74340.6 64114.4 64614.4 66614.0 67241.3 68740.7 10122.6 10241.0 10321.7 10393.7 10478.3 43475.0 45219.1 46765.5 47688.8 49293.8 53227.5 53493.7 57993.1 57999.4 58109.7 13183.8 13245.8 13640.2 13746.9 13801.3 14323.4 14378.0 14388.3 14465.0 14534.9 13754.6 13764.4 13890.0 13939.9 14533.8 13303.6 13782.0 13913.2 14044.5 14211.5 12355.8 12423.3 12441.6 12469.8 12474.8 10928.4 11420.4 11572.2 11650.4 11671.4 10825.3 11108.8 11422.8 11441.3 11477.9 09:06:24 INFO - PROCESS | 17798 | -------- Summary: end -------- 09:06:24 INFO - PROCESS | 17798 | 09:06:25 INFO - TEST-INFO | 17798: exit 0 09:06:25 INFO - TEST-OK | dromaeo_css | took 1113499ms 09:06:25 INFO - TEST-START | kraken 09:06:25 INFO - operating with platform_type : linux_ 09:06:25 INFO - Initialising browser for kraken test... 09:06:25 INFO - TEST-INFO | started process 18118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVtNHuA/profile http://localhost:59504/getInfo.html) 09:06:29 INFO - PROCESS | 18118 | __metrics Screen width/height:1600/1200 09:06:29 INFO - PROCESS | 18118 | colorDepth:24 09:06:29 INFO - PROCESS | 18118 | Browser inner width/height: 1024/697 09:06:29 INFO - PROCESS | 18118 | __metrics 09:06:30 INFO - PROCESS | 18118 | 1486659990358 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:06:30 INFO - PROCESS | 18118 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:30 INFO - PROCESS | 18118 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:31 INFO - TEST-INFO | 18118: exit 0 09:06:31 INFO - Browser initialized. 09:06:31 INFO - Running cycle 1/1 for kraken test... 09:06:31 INFO - TEST-INFO | started process 18212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVtNHuA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:43 INFO - PROCESS | 18212 | RSS: Main: 137560064 09:06:43 INFO - PROCESS | 18212 | 09:06:45 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59504/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:46 INFO - PROCESS | 18212 | RSS: Main: 181895168 09:06:46 INFO - PROCESS | 18212 | 09:06:49 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59504/tests/kraken/driver.html?testName=audio-dft) 09:06:49 INFO - PROCESS | 18212 | RSS: Main: 172019712 09:06:49 INFO - PROCESS | 18212 | 09:06:52 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59504/tests/kraken/driver.html?testName=audio-fft) 09:06:53 INFO - PROCESS | 18212 | RSS: Main: 263770112 09:06:53 INFO - PROCESS | 18212 | 09:06:55 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59504/tests/kraken/driver.html?testName=audio-oscillator) 09:06:55 INFO - PROCESS | 18212 | RSS: Main: 169926656 09:06:55 INFO - PROCESS | 18212 | 09:06:57 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59504/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:58 INFO - PROCESS | 18212 | RSS: Main: 172650496 09:06:58 INFO - PROCESS | 18212 | 09:07:01 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59504/tests/kraken/driver.html?testName=imaging-darkroom) 09:07:02 INFO - PROCESS | 18212 | RSS: Main: 211169280 09:07:02 INFO - PROCESS | 18212 | 09:07:05 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59504/tests/kraken/driver.html?testName=imaging-desaturate) 09:07:06 INFO - PROCESS | 18212 | RSS: Main: 209612800 09:07:06 INFO - PROCESS | 18212 | 09:07:08 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59504/tests/kraken/driver.html?testName=json-parse-financial) 09:07:09 INFO - PROCESS | 18212 | RSS: Main: 207765504 09:07:09 INFO - PROCESS | 18212 | 09:07:10 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59504/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:07:11 INFO - PROCESS | 18212 | RSS: Main: 170315776 09:07:11 INFO - PROCESS | 18212 | 09:07:13 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:07:13 INFO - PROCESS | 18212 | RSS: Main: 177266688 09:07:13 INFO - PROCESS | 18212 | 09:07:15 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:07:16 INFO - PROCESS | 18212 | RSS: Main: 186560512 09:07:16 INFO - PROCESS | 18212 | 09:07:18 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:07:19 INFO - PROCESS | 18212 | RSS: Main: 196354048 09:07:19 INFO - PROCESS | 18212 | 09:07:22 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:22 INFO - PROCESS | 18212 | RSS: Main: 192114688 09:07:22 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | Cycle 1(1): loaded http://localhost:59504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59504/tests/kraken/driver.html?testName=ai-astar) 09:07:24 INFO - PROCESS | 18212 | RSS: Main: 241655808 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | __start_tp_report 09:07:24 INFO - PROCESS | 18212 | _x_x_mozilla_page_load 09:07:24 INFO - PROCESS | 18212 | _x_x_mozilla_page_load_details 09:07:24 INFO - PROCESS | 18212 | |i|pagename|runs| 09:07:24 INFO - PROCESS | 18212 | |0;ai-astar;109;111;111;111;110;111;110;109;113;111 09:07:24 INFO - PROCESS | 18212 | |1;audio-beat-detection;144;117;118;153;117;119;116;117;137;118 09:07:24 INFO - PROCESS | 18212 | |2;audio-dft;170;166;163;163;161;160;162;162;162;162 09:07:24 INFO - PROCESS | 18212 | |3;audio-fft;74;76;75;116;75;76;75;76;88;75 09:07:24 INFO - PROCESS | 18212 | |4;audio-oscillator;82;82;102;83;82;83;94;83;82;102 09:07:24 INFO - PROCESS | 18212 | |5;imaging-gaussian-blur;117;119;118;117;118;116;116;116;117;117 09:07:24 INFO - PROCESS | 18212 | |6;imaging-darkroom;147;146;146;146;146;147;148;147;146;146 09:07:24 INFO - PROCESS | 18212 | |7;imaging-desaturate;96;96;95;95;96;95;96;96;95;95 09:07:24 INFO - PROCESS | 18212 | |8;json-parse-financial;49;48;49;48;49;48;49;50;49;49 09:07:24 INFO - PROCESS | 18212 | |9;json-stringify-tinderbox;63;63;63;63;63;63;63;62;63;63 09:07:24 INFO - PROCESS | 18212 | |10;stanford-crypto-aes;85;75;79;84;81;77;76;79;75;90 09:07:24 INFO - PROCESS | 18212 | |11;stanford-crypto-ccm;131;131;125;124;129;125;120;121;160;142 09:07:24 INFO - PROCESS | 18212 | |12;stanford-crypto-pbkdf2;158;158;157;162;169;156;158;156;165;160 09:07:24 INFO - PROCESS | 18212 | |13;stanford-crypto-sha256-iterative;62;60;62;61;61;61;62;65;60;60 09:07:24 INFO - PROCESS | 18212 | __end_tp_report 09:07:24 INFO - PROCESS | 18212 | __start_cc_report 09:07:24 INFO - PROCESS | 18212 | _x_x_mozilla_cycle_collect,1532 09:07:24 INFO - PROCESS | 18212 | __end_cc_report 09:07:24 INFO - PROCESS | 18212 | __startTimestamp1486660044840__endTimestamp 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | ------- Summary: start ------- 09:07:24 INFO - PROCESS | 18212 | Number of tests: 14 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.17 (1.1%) stddev-sans-first:1.09 09:07:24 INFO - PROCESS | 18212 | Values: 109.0 111.0 111.0 111.0 110.0 111.0 110.0 109.0 113.0 111.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:118.50 stddev:13.71 (11.6%) stddev-sans-first:12.83 09:07:24 INFO - PROCESS | 18212 | Values: 144.0 117.0 118.0 153.0 117.0 119.0 116.0 117.0 137.0 118.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.50 stddev:2.88 (1.8%) stddev-sans-first:1.66 09:07:24 INFO - PROCESS | 18212 | Values: 170.0 166.0 163.0 163.0 161.0 160.0 162.0 162.0 162.0 162.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.50 stddev:13.08 (17.3%) stddev-sans-first:13.66 09:07:24 INFO - PROCESS | 18212 | Values: 74.0 76.0 75.0 116.0 75.0 76.0 75.0 76.0 88.0 75.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:82.50 stddev:8.46 (10.3%) stddev-sans-first:8.74 09:07:24 INFO - PROCESS | 18212 | Values: 82.0 82.0 102.0 83.0 82.0 83.0 94.0 83.0 82.0 102.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.99 (0.8%) stddev-sans-first:1.05 09:07:24 INFO - PROCESS | 18212 | Values: 117.0 119.0 118.0 117.0 118.0 116.0 116.0 116.0 117.0 117.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 09:07:24 INFO - PROCESS | 18212 | Values: 147.0 146.0 146.0 146.0 146.0 147.0 148.0 147.0 146.0 146.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:07:24 INFO - PROCESS | 18212 | Values: 96.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 95.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#8] json-parse-financial Cycles:10 Average:48.80 Median:49.00 stddev:0.63 (1.3%) stddev-sans-first:0.67 09:07:24 INFO - PROCESS | 18212 | Values: 49.0 48.0 49.0 48.0 49.0 48.0 49.0 50.0 49.0 49.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:63.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 09:07:24 INFO - PROCESS | 18212 | Values: 63.0 63.0 63.0 63.0 63.0 63.0 63.0 62.0 63.0 63.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.00 stddev:4.93 (6.2%) stddev-sans-first:4.90 09:07:24 INFO - PROCESS | 18212 | Values: 85.0 75.0 79.0 84.0 81.0 77.0 76.0 79.0 75.0 90.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:130.00 stddev:12.05 (9.3%) stddev-sans-first:12.78 09:07:24 INFO - PROCESS | 18212 | Values: 131.0 131.0 125.0 124.0 129.0 125.0 120.0 121.0 160.0 142.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.90 Median:159.00 stddev:4.25 (2.7%) stddev-sans-first:4.46 09:07:24 INFO - PROCESS | 18212 | Values: 158.0 158.0 157.0 162.0 169.0 156.0 158.0 156.0 165.0 160.0 09:07:24 INFO - PROCESS | 18212 | 09:07:24 INFO - PROCESS | 18212 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.51 (2.4%) stddev-sans-first:1.58 09:07:24 INFO - PROCESS | 18212 | Values: 62.0 60.0 62.0 61.0 61.0 61.0 62.0 65.0 60.0 60.0 09:07:24 INFO - PROCESS | 18212 | -------- Summary: end -------- 09:07:24 INFO - PROCESS | 18212 | 09:07:25 INFO - TEST-INFO | 18212: exit 0 09:07:25 INFO - TEST-OK | kraken | took 60070ms 09:07:25 INFO - SUITE-END | took 1173s 09:07:26 INFO - Completed test suite (00:19:33) 09:07:26 INFO - javascript benchmark 09:07:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3305.1076209423454, 3316.2206881382886, 3322.053214833773, 3335.099523128755, 3375.848086836092, 5454.127339625661, 5493.313335833021, 5573.08044172711, 5586.0, 5600.52395546379, 5474.151506516487, 5527.778888844441, 5534.08687566551, 5534.667919924807, 5576.665400075988, 4926.679765815219, 4943.406791185045, 4967.850964471054, 5007.774650140735, 5012.72430016349, 4876.853694389156, 4892.6575139740235, 4902.289168070628, 4909.386326709161, 4984.775685094161, 5005.699658020512, 5020.39755229373, 5033.899322013576, 5057.317262169615, 5059.974700126494, 4995.125852975715, 5023.296738456619, 5025.673331233458, 5046.369203849537, 5058.190689489682, 4816.54242846929, 4832.226843705025, 4857.854264372039, 4866.975665121652, 4966.726830024347, 5385.838424847261, 5387.084195686703, 5397.4062853086125, 5401.675899446046, 5447.918281225785, 5862.912056319158, 5873.471387575096, 5876.9118463223085, 5877.089051197071, 5884.941150588482, 5706.914396284059, 5734.254546908917, 5865.472107510344, 5910.290765108171, 5910.468057874769, 5471.835844924659, 5536.390996990328, 5545.112781954868, 5574.86062848428, 5577.581681373913, 6013.609115407485, 6016.518678505709, 6018.638881667114, 6027.126066720301, 6035.366286539938, 5696.544276457874, 5733.054046082433, 5830.650160990354, 5831.504322132581, 5831.708414579254, 5819.185314056035, 5826.650400975955, 5872.677002764889, 5928.110783382441, 5963.701814909237, 5814.883702325929, 5885.823425297248, 5894.823155305347, 5905.852353691193, 5906.704664766745, 5653.886922261532, 5908.350081491032, 5918.201042859245, 5920.970395147997, 5925.466707996215, 4528.230200865795, 4564.954350456519, 4589.33454649074, 4615.053913947632, 4619.076184762994, 4066.4103704962613, 4072.531658859269, 4075.653569446571, 4081.836726530965, 4095.877123686294, 5670.943290567042, 5718.085106382959, 5755.654660720286, 5760.596758226884, 5789.421057894177, 5823.242978412779, 5827.708614569255, 5859.736311865976, 5890.764369425261, 5953.880922381615, 5715.142728590745, 5720.370759216483, 5722.77108915647, 5728.255326807572, 5730.283714535683, 4963.6277279204205, 5055.772490237948, 5063.822766203165, 5066.721330326867, 5100.362454693163, 6054.3945605439985, 6123.069106197231, 6160.599561028519, 6162.568620196633, 6278.968605156945, 5956.76172953077, 5988.984173464352, 6012.428819262163, 6013.158157857991, 6049.546284028627, 6267.185265915402, 6294.905576416449, 6297.992321228581, 6304.89664308735, 6307.936920630918, 5734.311882574118, 5766.308043034863, 5790.478856902899, 5867.178594996789, 5884.823455296348, 6019.608725432833, 6025.969870150621, 6029.3669164737075, 6030.939690603038, 6048.788292409745, 5891.499222565958, 5893.292804863444, 5894.0274854648505, 5895.145203945318, 5896.204012458264, 5587.189857470726, 5698.202251684685, 5715.971420142872, 5733.741981610921, 5865.032269675456, 5802.4197580241635, 5806.912896306474, 5812.82561523155, 5822.737976791139, 5844.12338149281, 5783.94216057834, 5836.299644042572, 5839.532837372915, 5841.036229022164, 5846.824595262149, 5514.145307477347, 5529.474699903502, 5639.887202256014, 5676.091825307931, 5682.715864206691], "value": 5459.522881045941, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.9274694289742, 601.4376557918346, 623.224086012914, 628.1739512541009, 642.8135840606225, 36760.81619591902, 45989.77005114974, 47791.522084779186, 47920.0, 47939.760301198534, 51528.48471515284, 51808.74095629526, 52014.73992630032, 52056.47943520573, 52080.0, 8833.0, 9339.15947564719, 9528.904710952906, 9538.90461095387, 9538.952305238465, 9099.181073703365, 10755.408452535108, 11089.83365249522, 11112.333260004385, 11507.482163302671, 11400.030997365235, 11423.885761142365, 11492.080633549296, 11495.19533632646, 11518.596849110283, 11424.200305978586, 11450.541978320856, 11473.65579032622, 11483.081353491702, 11492.253003554742, 11259.1555633327, 11335.0, 11338.263012904135, 11339.489722962486, 11346.546138154546, 47195.0, 47299.76350118262, 47546.5245347549, 47580.0, 47635.285470717965, 4314.71954322968, 4422.491413487426, 4524.977375113103, 4537.205988951953, 4702.106599746037, 32164.678353216637, 32376.838115809507, 32383.352332953207, 32426.837865810758, 33330.666693333, 4570.223062079422, 4617.8152873885, 4672.299155126758, 4684.8360307389075, 4778.546038126407, 371346.0, 371818.0, 372473.0, 372526.0, 372604.0, 59509.702451487465, 59797.701011495534, 59846.70076649676, 59872.401275987555, 59977.70011150004, 62520.687396562724, 62553.374466254754, 62614.0, 62711.372886270554, 62771.37228627747, 17337.82662173362, 17573.824261757476, 17615.207315670825, 17624.55938601545, 17633.7354939676, 12547.372631368395, 12605.558805441766, 12633.93683031579, 12795.0, 13221.206727596182, 18753.34363297278, 19422.28007591622, 19549.404827235347, 19573.719885912364, 19638.803611963984, 7238.746643867439, 7309.415246780245, 7518.210587888193, 7585.734499292498, 7833.569153696488, 934.5794392523392, 938.9953050234706, 940.9294302927171, 947.2564037230741, 952.7808604020976, 31768.0, 32610.0, 33171.336573268396, 33353.49969750456, 33796.15509612279, 997.3892164154838, 1051.7002654243656, 1111.2776695148218, 1118.4799068433217, 1136.676047326508, 16709.6658066838, 17609.085475194464, 17747.0, 17783.73324400135, 17899.19453624564, 531010.0, 533946.0, 539127.0, 541380.0, 541773.0, 70691.0, 70803.64598176976, 70842.64578677074, 70977.0, 71403.64298178475, 1553.937842486288, 1567.3495499367862, 1577.5582836805484, 1593.6971975324652, 1599.128474981138, 34772.478412824334, 35839.64160358467, 36046.09884752902, 36154.27691446209, 36167.27665446729, 60271.39728602658, 62350.0, 62445.06332404927, 62460.0, 62474.37525624686, 486146.0, 489336.0, 489363.0, 491402.0, 493119.0, 42308.0, 43092.56907431011, 46000.0, 48473.0, 50213.74893125511, 50027.749861250464, 53561.19658205052, 54006.0, 54251.72874135604, 55560.0, 43157.784211078746, 43621.781891090344, 45223.547764521936, 45911.0, 46588.30117548172, 40997.18005639811, 41716.0, 41728.37407438952, 42656.78671606622, 42702.359464607434, 43818.56181438145, 43842.56157438385, 43894.34158487561, 44271.0, 44460.55539444564, 37025.0, 39359.0, 39477.802610986764, 39594.80202599084, 39952.20095598014, 29267.853660731562, 29342.0, 29757.577878544245, 29782.404351912406, 29797.0], "value": 22188.222732821076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.16256786969024, 440.5660424481883, 444.50424322155936, 447.0544797752754, 450.7137967390706, 19647.60704785905, 21171.259005934775, 21646.89176554119, 21772.237971670995, 21940.45148871277, 20730.0, 22206.333809985685, 22246.55506889865, 22255.888720556373, 22413.66379504309, 5920.378360272174, 5936.64380137191, 6162.22972128484, 6165.445109940105, 6168.568200225986, 5982.8504287392725, 6046.697665116749, 6090.056041313604, 6096.695165241742, 6096.908546371808, 6024.337322894478, 6136.662483563402, 6144.723487443053, 6147.385261473862, 6214.78811631732, 6336.58812177207, 6359.523035772289, 6370.076338930852, 6371.968140159316, 6373.681315934208, 6122.295935967376, 6213.099100630383, 6251.062340648894, 6256.124142619991, 6260.499160067184, 15366.769498457532, 15629.218539073001, 23010.76989230103, 25358.746412535824, 25795.74204257952, 3143.2141964508874, 3148.7481001519823, 3179.8728050877935, 3216.662250463691, 3234.126785767853, 10906.291091079134, 11147.498362573704, 11324.377159256237, 11345.602903898407, 11408.315501069963, 2021.88227139958, 2024.3623258673472, 2026.1186383922893, 2031.8476114291486, 2052.7639321478073, 96560.0, 96753.51623241839, 97614.0, 98029.0, 98692.50653746685, 12204.694882628006, 12293.139480236297, 12317.753644927052, 12602.306873122066, 12650.620481385573, 17716.557086072953, 17874.374396896048, 17887.0, 17904.0, 17919.193636286138, 8958.417702849294, 9009.324300677476, 9073.954630226806, 9121.45271283725, 9147.542622868963, 8444.408891377545, 8526.445781024213, 8526.87209691855, 8741.388102832869, 8760.0, 5165.431802501722, 5230.581553475713, 5238.73806309683, 5256.947430525723, 5548.5838562108, 2097.1820989813987, 2129.169623846701, 2150.827933765295, 2211.668249762516, 2214.9446263843533, 878.2271600991214, 893.1515060692368, 897.3942588752697, 901.3150005995491, 917.6879860847343, 19411.0, 19944.20223191056, 19973.800261997196, 20024.799752002295, 20465.79534204639, 911.49867572836, 913.4199783137756, 916.7983043730369, 919.1038737231146, 923.4828496042238, 10751.139908807276, 11148.386838723785, 11196.328220306645, 11263.60577379804, 11367.94316028431, 663860.0, 693395.0, 695154.0, 705805.0, 723539.0, 97978.0, 114471.0, 115088.0, 115701.42149289369, 116815.8318416822, 1063.9148868090533, 1078.4122653153893, 1083.7778255457613, 1101.1081024370285, 1106.269861891148, 17611.38360157388, 17799.377021804175, 18146.18342174579, 18166.72749908726, 18486.722699159254, 43034.35448468213, 45634.771826140655, 46133.538664614265, 46611.30083048689, 48090.27864581964, 56824.431755681915, 66332.33667663262, 67239.32760672331, 67370.66314668395, 67850.66074669595, 9461.526923653928, 9856.507174641383, 9881.357711748713, 9990.100890919852, 9998.600055997678, 14942.178180199759, 14963.551093467213, 14990.0, 15321.846781532042, 15561.0, 12145.14983951115, 12432.440540175532, 12717.300548469922, 12889.291088990196, 13072.542461013818, 11835.408229588658, 11982.64052078439, 12165.939170304091, 12354.505819767108, 12640.620781376205, 12032.939835300767, 12228.571999979958, 12455.626331210078, 12461.0, 12657.0, 9568.4737339447, 9625.32622716403, 9663.903360966582, 9743.853842192515, 9815.803683926424, 11258.887411125785, 11752.412379380825, 11798.469068891749, 11841.348725820162, 11886.0], "value": 10108.934023882026, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.784834426492, 1456.7159403916235, 1483.3769816676995, 1487.7693957436595, 1487.8586534279239, 7826.0, 8254.711085112021, 8318.085010648823, 8521.786955326112, 8810.07494213108, 8916.331275154373, 8973.641054357819, 8984.595693193822, 9008.864867027, 9026.90973090269, 2140.090461553841, 2206.9448263793374, 2386.9761302387014, 2429.878506074698, 2430.6232533957177, 2366.4557151855083, 2456.828022038458, 2457.9262622121278, 2479.4917042006437, 2480.615504596781, 2327.7090363704538, 2328.6390609455475, 2334.6498025296255, 2351.1535847095033, 2363.869987150706, 2399.076355603088, 2414.8188885833547, 2423.018677435641, 2425.6482809992453, 2437.122635851092, 2340.2277248507953, 2352.776486233805, 2354.9646755298686, 2358.5518751437344, 2359.7050368703913, 9027.277817774562, 9050.411723238034, 9110.5444727764, 9121.863172052424, 9128.543572821332, 5280.075986702312, 5379.300690910196, 5421.430749771296, 5425.647332923348, 5428.131498960148, 3350.3131857969056, 3493.615702272748, 3553.5558055243096, 3597.388443964532, 3670.614585468514, 1372.5813626843808, 1482.2366481262159, 1525.3898440623727, 1529.4646873594265, 1554.7123782100234, 10932.890671093266, 11109.555617775279, 11204.15968802343, 11762.059035277158, 11813.114016448715, 4442.955570444255, 4616.838410655678, 4629.629629629621, 4680.321353403738, 4689.95310046902, 9424.717258482257, 9449.244060475145, 9455.669051583161, 9466.384684995592, 9488.335816492918, 5543.417941116206, 5547.389787123413, 5548.250986116904, 5548.4174161713245, 5552.611317207757, 4311.417958575552, 4967.7019378837385, 4975.751212439436, 5081.161608334583, 5135.6918584885025, 3853.4412510185866, 3947.348687466593, 3976.5029371328583, 4009.8195581198916, 4028.5568587455355, 1213.1023042948332, 1225.9509619615294, 1226.3316492511603, 1232.4885172653428, 1239.783037968352, 969.1616751510035, 986.4229425785852, 990.1435258501344, 996.8834904906406, 1007.959681612742, 5641.887162256732, 5672.319321681424, 5704.8859022819315, 5762.164486149569, 5764.827055188351, 589.8407429993878, 606.5390303369383, 615.4553220399978, 623.7598524567983, 626.2422468812697, 3878.108035151931, 3913.5303763547986, 3941.0147463134217, 4109.876703698894, 4121.608447197512, 10345.37927724339, 10912.61805836792, 10935.945320273348, 10945.562177512811, 10946.288491248044, 10019.649312274036, 10326.380417174843, 10781.137508999262, 10866.424368466529, 10904.400257985886, 8052.879206811903, 8167.060788009455, 8168.42821002536, 8208.179182081743, 8209.425340226127, 5539.8892022160135, 6051.183090282755, 6074.210352654127, 6142.815715528542, 6190.7214175363115, 9200.95399522998, 10107.7473063174, 10126.797464050826, 10135.493225338556, 10152.543135559064, 9701.0, 9878.506074696093, 9905.603775849173, 9969.252306077218, 9996.950015249877, 7264.382527485011, 7269.0, 7615.885761713689, 7831.686732530635, 8004.719834805754, 8622.26710729595, 8773.736787896374, 8786.516741579275, 8796.868046979429, 8804.867926980973, 7738.380929525737, 7738.4969976951315, 7754.263344982218, 7825.843483130192, 7827.92172078272, 7659.195784442776, 7714.15144334134, 7716.961415193113, 7810.218978102144, 7903.486273392211, 6688.799336019927, 6724.6886857064355, 6785.18577770651, 6790.96604516994, 6810.5913645181445, 4959.752012399294, 5061.114304996685, 5089.618278629044, 5167.948320516897, 5461.180822876441, 5247.449017853223, 5290.523852853108, 5342.278792362981, 5354.518093371616, 5374.623776335619], "value": 4611.553275380341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.35143068229473, 412.1386302627508, 414.08280658341766, 425.5829287298449, 427.0966904995935, 5252.71110088945, 5330.28041214436, 5342.691040695036, 5369.489898459645, 5391.005328028625, 5060.671056381342, 5090.338256026711, 5170.741462926858, 5191.818286359978, 5270.9736451317685, 4260.573942605738, 4260.8721738347895, 4267.679924005696, 4275.8075886585175, 4276.550962148976, 4243.896586887416, 4248.4052232687445, 4283.764392958386, 4291.8497852575165, 4317.71934824237, 4131.669466442678, 4186.706930514865, 4214.97892510537, 4216.177845320164, 4223.324268117102, 4192.769397683126, 4204.306289462265, 4213.789310534492, 4215.725977811432, 4224.936625950613, 3847.1343947611845, 3861.5905194603765, 3864.5555761087553, 3868.129670824042, 3868.1490072184356, 4998.0, 5101.20931255656, 5122.5133612307145, 5190.92213616796, 5199.0, 4837.467878533358, 4869.5130486951375, 4876.414830220361, 4895.657303988723, 4914.533119353656, 5545.556355491551, 5548.167774833767, 5559.6664200148125, 5572.804951826707, 5582.162675598652, 4212.473440819897, 4244.681648876347, 4261.272785417335, 4296.2696341621995, 4324.113556720896, 5672.290963629546, 5693.601447898649, 5704.572157088193, 5707.401927460276, 5743.386759024634, 4395.450568678915, 4409.823607055682, 4427.424434823452, 4432.268675668543, 4453.75504347264, 4313.331433627793, 4452.508409682792, 4483.327500874895, 4523.909521809611, 4533.863984080483, 4947.925781113214, 4966.6771659842, 4990.226514890198, 5001.24981252815, 5004.574611158092, 5641.70240844608, 5656.472752356923, 5677.971610142005, 5715.313982375209, 5716.0, 1438.3239877257672, 1479.9112053276622, 1483.7922690823293, 1508.351408894186, 1540.7688846673088, 1045.7072019834459, 1055.5883205549842, 1081.9342947197006, 1089.1831126655009, 1091.4106382553332, 5280.762365693553, 5301.390340110936, 5310.283111779938, 5315.681059136464, 5328.6802791832615, 5483.890322193533, 5491.940522304136, 5494.752736126884, 5496.81818409044, 5506.448340820229, 5084.593232541388, 5272.947270527322, 5319.255304257407, 5322.494363035506, 5338.3060202173465, 2879.8560071996317, 2886.422715456917, 2907.680155182928, 2924.8537573121685, 2925.7659387249396, 5699.658020518782, 5707.914381284284, 5720.885582288331, 5721.5708821838525, 5751.281089863767, 5345.786168553214, 5555.222388805597, 5575.609707320448, 5623.268975033219, 5646.632968857011, 5736.569757268222, 5748.798792042342, 5768.779020077217, 5776.855578610568, 5793.971030144738, 5387.515123638891, 5484.367503598375, 5486.561075113901, 5533.335999680065, 5562.276904002454, 5612.01789686795, 5618.269624948731, 5627.396192085236, 5628.127640215773, 5645.71771411436, 5933.546281161143, 5935.228420305332, 5987.251593550806, 5987.491063259672, 6002.099685047103, 4029.106320029581, 4145.108801607669, 4150.584941505826, 4180.9163816722885, 4203.369494575838, 5699.943000569941, 5704.885902282014, 5706.372299047184, 5710.885782284248, 5715.0, 5380.246765452762, 5423.4034256231, 5489.945100549103, 5547.278853749148, 5570.582206334622, 5495.450454954473, 5511.696856672761, 5532.529734972572, 5545.279113715191, 5571.164325351067, 5357.973210133924, 5502.972485137549, 5537.307836520435, 5565.805196818092, 5577.191307260506, 5567.192757050286, 5568.415316391883, 5568.470995255444, 5580.804671836466, 5602.747876345493, 5418.431064738145, 5437.8368648940595, 5455.3453585568395, 5457.199124289054, 5487.176923461513], "value": 4324.116349633352, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.9880901786478, 810.939179561533, 825.3809642767924, 925.9259259259258, 934.6632521896829, 16731.665366692658, 17256.82743172568, 17973.730394044083, 17982.640347193083, 17992.55018624535, 16336.918315408437, 16592.917035414837, 16611.83388166118, 16617.83382166178, 16622.75065874013, 712.2023333866065, 724.9673764680575, 822.1696086952192, 838.4382463749272, 839.1273076000982, 846.3609974469267, 847.2374862623657, 847.4152834544176, 848.0798333807795, 848.4357901995165, 888.5512816027897, 890.2032680750724, 891.5987805487524, 891.7993451473399, 894.1996912763074, 744.4044764188627, 759.5177062565283, 1092.7049696581958, 1138.4421633399575, 1141.0928311991986, 647.4043879630703, 649.0880313160018, 649.506375154881, 650.0217173154373, 651.1697585578378, 16007.119608421532, 16330.83669163305, 17551.122443877754, 17581.7362739559, 17601.0, 16420.261088251056, 16747.413840515524, 16795.16024198785, 16882.915585421993, 17758.733618995724, 12902.548410805577, 13096.934515327363, 13222.669433264246, 13266.336683165802, 13297.401616927262, 4021.718479706422, 4043.939340909889, 4052.736572122832, 4123.835046598132, 4130.710850240484, 68852.6557367215, 75279.24720752778, 79573.0, 80440.0, 81213.0, 12236.75526489483, 12697.047721420746, 12723.23660580368, 12755.808662870064, 12806.743865122831, 23382.883085584803, 23385.181518646765, 23423.41441463943, 23448.06207751705, 23846.16538421147, 9469.47917864524, 9479.431234125837, 9622.807543849083, 9637.614495420245, 9720.416774993382, 5068.467810879878, 5077.1876499759865, 5092.414372347153, 5216.686998780086, 5232.633715639945, 3909.765414075108, 4005.0588111793704, 4005.619466150711, 4028.053407449247, 4073.124278280184, 648.7859006527834, 652.6182183422751, 654.5156584127711, 666.6500087454028, 667.889798183304, 794.7734895554739, 823.4071468542639, 827.3091968206614, 831.7296878514506, 846.8602680557763, 10797.974192451704, 11092.33445993243, 11268.323900565993, 11474.770504589862, 11601.825972610417, 730.2223132364015, 734.4565021883767, 747.3050063440943, 751.1136858506906, 751.4514404484647, 4960.503949605083, 5249.133892907704, 5300.549453296513, 5310.6813591184655, 5339.679619222781, 81662.0, 82426.58786706028, 84206.578967106, 85384.57307713546, 87512.56243718868, 71009.0, 71967.64016179886, 72223.63888180525, 73196.0, 74340.62829685817, 64114.358856411774, 64614.35385646178, 66614.0, 67241.32758672448, 68740.6562967192, 10122.645707400206, 10241.0, 10321.74195645115, 10393.740156496147, 10478.318909270873, 43475.0, 45219.09561808811, 46765.53234467748, 47688.761556191996, 49293.75353123211, 53227.4677253238, 53493.7325313371, 57993.130103047646, 57999.4200057994, 58109.70945145247, 13183.813456788936, 13245.801312980506, 13640.249786261455, 13746.93126534361, 13801.309934503435, 14323.355449005028, 14378.0, 14388.28058597045, 14465.0, 14534.927325363305, 13754.587362379145, 13764.449422023006, 13890.0, 13939.860601394263, 14533.781993269899, 13303.600891973256, 13782.0, 13913.234772087633, 14044.508442204473, 14211.50259740904, 12355.836187285435, 12423.254604723385, 12441.564545240873, 12469.75060498803, 12474.750504989668, 10928.37188847728, 11420.371879546705, 11572.18994670365, 11650.359230242419, 11671.35807530594, 10825.296355736851, 11108.777824443627, 11422.828657569977, 11441.313521188755, 11477.885221148015], "value": 7750.17510662586, "unit": "score"}], "name": "dromaeo_css", "value": 7577.301800838654, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 111.0, 110.0, 111.0, 110.0, 109.0, 113.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 118.0, 153.0, 117.0, 119.0, 116.0, 117.0, 137.0, 118.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 163.0, 163.0, 161.0, 160.0, 162.0, 162.0, 162.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 116.0, 75.0, 76.0, 75.0, 76.0, 88.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 102.0, 83.0, 82.0, 83.0, 94.0, 83.0, 82.0, 102.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 118.0, 117.0, 118.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 148.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 49.0, 48.0, 49.0, 48.0, 49.0, 50.0, 49.0, 49.0], "value": 48.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 75.0, 79.0, 84.0, 81.0, 77.0, 76.0, 79.0, 75.0, 90.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 125.0, 124.0, 129.0, 125.0, 120.0, 121.0, 160.0, 142.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 157.0, 162.0, 169.0, 156.0, 158.0, 156.0, 165.0, 160.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 62.0, 61.0, 61.0, 61.0, 62.0, 65.0, 60.0, 60.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1470.4, "alertThreshold": 2.0}]} 09:07:26 INFO - Return code: 0 09:07:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:07:26 ERROR - # TBPL SUCCESS # 09:07:26 INFO - Running post-action listener: _resource_record_post_action 09:07:26 INFO - [mozharness: 2017-02-09 17:07:26.134966Z] Finished run-tests step (success) 09:07:26 INFO - Running post-run listener: _resource_record_post_run 09:07:26 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 367583232; Read time: 180; Write time: 247988 09:07:26 INFO - TinderboxPrint: CPU usage
13.0% 09:07:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 180 09:07:26 INFO - TinderboxPrint: I/O write bytes / time
367,583,232 / 247,988 09:07:26 INFO - TinderboxPrint: CPU idle
8,260.6 (87.0%) 09:07:26 INFO - TinderboxPrint: CPU user
1,191.0 (12.5%) 09:07:26 INFO - TinderboxPrint: Swap in / out
0 / 0 09:07:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114524160; Read time: 0; Write time: 112916 09:07:26 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 253059072; Read time: 180; Write time: 135072 09:07:26 INFO - Running post-run listener: _upload_blobber_files 09:07:26 INFO - Blob upload gear active. 09:07:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:07:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:07:26 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', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:07:26 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 Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:07:27 INFO - (blobuploader) - INFO - Open directory for files ... 09:07:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:07:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:07:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:28 INFO - (blobuploader) - INFO - Done attempting. 09:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:07:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:28 INFO - (blobuploader) - INFO - Done attempting. 09:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:07:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:29 INFO - (blobuploader) - INFO - Done attempting. 09:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:07:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:33 INFO - (blobuploader) - INFO - Done attempting. 09:07:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:33 INFO - Return code: 0 09:07:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:33 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d14cd28307a3d4877362a7fe3f3778b07036d4b61d7df3cdb281ea902920c8c7f086e1895645e742a9a3987ae199ab6548c7a4c57e35f3f30bf88bfff36de736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/97ad85c7fbe83a46908f08f103124b24b804d798271ee808cc821734342769c32d1297e3a02834ae0eb06e59a422d3fd0cc61a2cd5b974afea6a765428beafa7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c59f9e73ad49d153765d7f009518cd26ef6044c73671f57fc7ad8c384f11d02494b4f7a7dc97a466a2002f5e66c2bf14217c575285dca2bd0f615d248a87a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba4485bbfd3e0a5293743460c5c9d440c9cb2598441f4786c384ecdb3c53fbb44640a559f344875a4074a7c4859ec41bbb053e302641bb3c059e7a99e0a7230f"} 09:07:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:33 INFO - Contents: 09:07:33 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d14cd28307a3d4877362a7fe3f3778b07036d4b61d7df3cdb281ea902920c8c7f086e1895645e742a9a3987ae199ab6548c7a4c57e35f3f30bf88bfff36de736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/97ad85c7fbe83a46908f08f103124b24b804d798271ee808cc821734342769c32d1297e3a02834ae0eb06e59a422d3fd0cc61a2cd5b974afea6a765428beafa7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c59f9e73ad49d153765d7f009518cd26ef6044c73671f57fc7ad8c384f11d02494b4f7a7dc97a466a2002f5e66c2bf14217c575285dca2bd0f615d248a87a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba4485bbfd3e0a5293743460c5c9d440c9cb2598441f4786c384ecdb3c53fbb44640a559f344875a4074a7c4859ec41bbb053e302641bb3c059e7a99e0a7230f"} 09:07:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:33 INFO - Copying logs to upload dir... 09:07:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.309277 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-02-09 09:07:32.804060) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 09:07:32.807457) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d14cd28307a3d4877362a7fe3f3778b07036d4b61d7df3cdb281ea902920c8c7f086e1895645e742a9a3987ae199ab6548c7a4c57e35f3f30bf88bfff36de736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/97ad85c7fbe83a46908f08f103124b24b804d798271ee808cc821734342769c32d1297e3a02834ae0eb06e59a422d3fd0cc61a2cd5b974afea6a765428beafa7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c59f9e73ad49d153765d7f009518cd26ef6044c73671f57fc7ad8c384f11d02494b4f7a7dc97a466a2002f5e66c2bf14217c575285dca2bd0f615d248a87a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba4485bbfd3e0a5293743460c5c9d440c9cb2598441f4786c384ecdb3c53fbb44640a559f344875a4074a7c4859ec41bbb053e302641bb3c059e7a99e0a7230f"} build_url:https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/Sfm-yZw_Txq3_5zTsQbTxA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d14cd28307a3d4877362a7fe3f3778b07036d4b61d7df3cdb281ea902920c8c7f086e1895645e742a9a3987ae199ab6548c7a4c57e35f3f30bf88bfff36de736", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/97ad85c7fbe83a46908f08f103124b24b804d798271ee808cc821734342769c32d1297e3a02834ae0eb06e59a422d3fd0cc61a2cd5b974afea6a765428beafa7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c59f9e73ad49d153765d7f009518cd26ef6044c73671f57fc7ad8c384f11d02494b4f7a7dc97a466a2002f5e66c2bf14217c575285dca2bd0f615d248a87a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba4485bbfd3e0a5293743460c5c9d440c9cb2598441f4786c384ecdb3c53fbb44640a559f344875a4074a7c4859ec41bbb053e302641bb3c059e7a99e0a7230f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 09:07:32.835496) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 09:07:32.835814) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1486658813.641654-212305359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 09:07:32.918338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 09:07:32.918757) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 09:07:32.919100) ========= ========= Total master_lag: 0.16 =========