builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-043 starttime: 1481822720.82 results: success (0) buildid: 20161215074604 builduid: 4831264020c54f2ca1ba95d376b5c318 revision: 05f6c6e2e3d45128c268b6681980a91febabfab2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:20.822767) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:20.823171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:20.823439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:20.837739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:20.838004) ========= 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=d2a8c569965ab8367867b893000001a1-1481822721.17288-795910151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-15 09:25:21-- 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% 17.3M=0.001s 2016-12-15 09:25:22 (17.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172911 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:21.024461) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:21.024766) ========= 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=d2a8c569965ab8367867b893000001a1-1481822721.17288-795910151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020118 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:21.074029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:21.074372) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 05f6c6e2e3d45128c268b6681980a91febabfab2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 05f6c6e2e3d45128c268b6681980a91febabfab2 --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=d2a8c569965ab8367867b893000001a1-1481822721.17288-795910151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-15 09:25:22,125 truncating revision to first 12 chars 2016-12-15 09:25:22,125 Setting DEBUG logging. 2016-12-15 09:25:22,125 attempt 1/10 2016-12-15 09:25:22,125 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/05f6c6e2e3d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 09:25:22,693 unpacking tar archive at: graphics-05f6c6e2e3d4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944199 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:22.032353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:22.032669) ========= script_repo_revision: 05f6c6e2e3d45128c268b6681980a91febabfab2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:22.033081) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:22.033348) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 09:25:22.041403) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-15 09:25:22.041688) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1481822721.17288-795910151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:25:23 INFO - MultiFileLogger online at 20161215 09:25:23 in /builds/slave/test 09:25:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:25:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:25:23 INFO - {'append_to_log': False, 09:25:23 INFO - 'base_work_dir': '/builds/slave/test', 09:25:23 INFO - 'blob_upload_branch': 'Graphics', 09:25:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:25:23 INFO - 'branch': 'Graphics', 09:25:23 INFO - 'buildbot_json_path': 'buildprops.json', 09:25:23 INFO - 'config_files': ('talos/linux_config.py',), 09:25:23 INFO - 'default_actions': ('clobber', 09:25:23 INFO - 'read-buildbot-config', 09:25:23 INFO - 'download-and-extract', 09:25:23 INFO - 'populate-webroot', 09:25:23 INFO - 'create-virtualenv', 09:25:23 INFO - 'install', 09:25:23 INFO - 'run-tests'), 09:25:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:25:23 INFO - 'download_minidump_stackwalk': True, 09:25:23 INFO - 'download_symbols': 'ondemand', 09:25:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:25:23 INFO - 'tooltool.py': '/tools/tooltool.py', 09:25:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:25:23 INFO - '/tools/misc-python/virtualenv.py')}, 09:25:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:25:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:25:23 INFO - 'installer_path': 'installer.exe', 09:25:23 INFO - 'log_level': 'info', 09:25:23 INFO - 'log_name': 'talos', 09:25:23 INFO - 'log_to_console': True, 09:25:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:25:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:25:23 INFO - 'opt_config_files': (), 09:25:23 INFO - 'pip_index': False, 09:25:23 INFO - 'sps_profile': False, 09:25:23 INFO - 'sps_profile_interval': 0, 09:25:23 INFO - 'suite': 'dromaeojs', 09:25:23 INFO - 'system_bits': '32', 09:25:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:25:23 INFO - 'title': 'talos-linux64-ix-043', 09:25:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:25:23 INFO - 'use_talos_json': True, 09:25:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:25:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:25:23 INFO - 'work_dir': 'build'} 09:25:23 INFO - [mozharness: 2016-12-15 17:25:23.179395Z] Running clobber step. 09:25:23 INFO - Running pre-action listener: _resource_record_pre_action 09:25:23 INFO - Running main action method: clobber 09:25:23 INFO - rmtree: /builds/slave/test/build 09:25:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:25:24 INFO - Running post-action listener: _resource_record_post_action 09:25:24 INFO - [mozharness: 2016-12-15 17:25:24.155130Z] Finished clobber step (success) 09:25:24 INFO - [mozharness: 2016-12-15 17:25:24.155228Z] Running read-buildbot-config step. 09:25:24 INFO - Running pre-action listener: _resource_record_pre_action 09:25:24 INFO - Running main action method: read_buildbot_config 09:25:24 INFO - Using buildbot properties: 09:25:24 INFO - { 09:25:24 INFO - "project": "", 09:25:24 INFO - "product": "firefox", 09:25:24 INFO - "script_repo_revision": "default", 09:25:24 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 09:25:24 INFO - "repository": "", 09:25:24 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 09:25:24 INFO - "buildid": "20161215074604", 09:25:24 INFO - "pgo_build": "False", 09:25:24 INFO - "basedir": "/builds/slave/test", 09:25:24 INFO - "buildnumber": 21, 09:25:24 INFO - "slavename": "talos-linux64-ix-043", 09:25:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:25:24 INFO - "platform": "ubuntu64_hw", 09:25:24 INFO - "scheduler": "tests-graphics-linux64-talos", 09:25:24 INFO - "branch": "graphics", 09:25:24 INFO - "repo_path": "projects/graphics", 09:25:24 INFO - "revision": "05f6c6e2e3d45128c268b6681980a91febabfab2", 09:25:24 INFO - "stage_platform": "linux64", 09:25:24 INFO - "builduid": "4831264020c54f2ca1ba95d376b5c318", 09:25:24 INFO - "slavebuilddir": "test" 09:25:24 INFO - } 09:25:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:25:24 INFO - Running post-action listener: _resource_record_post_action 09:25:24 INFO - [mozharness: 2016-12-15 17:25:24.157955Z] Finished read-buildbot-config step (success) 09:25:24 INFO - [mozharness: 2016-12-15 17:25:24.158048Z] Running download-and-extract step. 09:25:24 INFO - Running pre-action listener: _resource_record_pre_action 09:25:24 INFO - Running main action method: download_and_extract 09:25:24 INFO - mkdir: /builds/slave/test/build/tests 09:25:24 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')]} 09:25:24 INFO - trying https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:25:24 INFO - Downloading https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:25:24 INFO - Downloaded 1361 bytes. 09:25:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:25:24 INFO - Using the following test package requirements: 09:25:24 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:25:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:25:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:25:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'jsshell-linux-x86_64.zip'], 09:25:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:25:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:25:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:25:24 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:25:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:25:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:25:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:25:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:25:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:25:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:25:24 INFO - Fetch https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:25:25 INFO - Expected file size: 20212885 09:25:25 INFO - Obtained file size: 20212885 09:25:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:25:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:25:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:25:29 INFO - Fetch https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:25:30 INFO - Expected file size: 20212885 09:25:30 INFO - Obtained file size: 20212885 09:25:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:25:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:25:34 INFO - Fetch https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:25:35 INFO - Expected file size: 14409734 09:25:35 INFO - Obtained file size: 14409734 09:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:35 INFO - trying https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:25:35 INFO - Downloading https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:25:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:25:37 INFO - Downloaded 55637244 bytes. 09:25:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:25:37 INFO - mkdir: /builds/slave/test/properties 09:25:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:25:37 INFO - Writing to file /builds/slave/test/properties/build_url 09:25:37 INFO - Contents: 09:25:37 INFO - build_url:https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:25:37 INFO - Running post-action listener: _resource_record_post_action 09:25:37 INFO - Running post-action listener: set_extra_try_arguments 09:25:37 INFO - [mozharness: 2016-12-15 17:25:37.751062Z] Finished download-and-extract step (success) 09:25:37 INFO - [mozharness: 2016-12-15 17:25:37.751287Z] Running populate-webroot step. 09:25:37 INFO - Running pre-action listener: _resource_record_pre_action 09:25:37 INFO - Running main action method: populate_webroot 09:25:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:25:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:25:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:25:37 INFO - '--tppagecycles', 09:25:37 INFO - '7'], 09:25:37 INFO - 'tests': ['tsvgm']}}, 09:25:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:25:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:25:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:25:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:25:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:25:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:25:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['damp', 'tps']}, 09:25:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'tests': ['damp', 'tps']}, 09:25:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['dromaeo_dom']}, 09:25:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:25:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['basic_compositor_video']}, 09:25:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:25:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['a11yr', 09:25:37 INFO - 'ts_paint', 09:25:37 INFO - 'tpaint', 09:25:37 INFO - 'sessionrestore', 09:25:37 INFO - 'sessionrestore_no_auto_restore', 09:25:37 INFO - 'tabpaint']}, 09:25:37 INFO - 'other-e10s': {'tests': ['a11yr', 09:25:37 INFO - 'ts_paint', 09:25:37 INFO - 'tpaint', 09:25:37 INFO - 'sessionrestore', 09:25:37 INFO - 'sessionrestore_no_auto_restore', 09:25:37 INFO - 'tabpaint']}, 09:25:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:25:37 INFO - 'ts_paint', 09:25:37 INFO - 'tpaint', 09:25:37 INFO - 'sessionrestore', 09:25:37 INFO - 'sessionrestore_no_auto_restore', 09:25:37 INFO - 'tabpaint']}, 09:25:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:25:37 INFO - 'ts_paint', 09:25:37 INFO - 'tpaint', 09:25:37 INFO - 'sessionrestore', 09:25:37 INFO - 'sessionrestore_no_auto_restore', 09:25:37 INFO - 'tabpaint']}, 09:25:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['a11yr', 09:25:37 INFO - 'ts_paint', 09:25:37 INFO - 'tpaint', 09:25:37 INFO - 'sessionrestore', 09:25:37 INFO - 'sessionrestore_no_auto_restore', 09:25:37 INFO - 'tabpaint']}, 09:25:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['a11yr', 09:25:37 INFO - 'ts_paint', 09:25:37 INFO - 'tpaint', 09:25:37 INFO - 'sessionrestore', 09:25:37 INFO - 'sessionrestore_no_auto_restore', 09:25:37 INFO - 'tabpaint']}, 09:25:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['tsvgx', 09:25:37 INFO - 'tsvgr_opacity', 09:25:37 INFO - 'tart', 09:25:37 INFO - 'tscrollx', 09:25:37 INFO - 'cart', 09:25:37 INFO - 'tsvg_static']}, 09:25:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:25:37 INFO - 'tsvgr_opacity', 09:25:37 INFO - 'tart', 09:25:37 INFO - 'tscrollx', 09:25:37 INFO - 'cart', 09:25:37 INFO - 'tsvg_static']}, 09:25:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'talos_options': ['--disable-e10s'], 09:25:37 INFO - 'tests': ['tp5o']}, 09:25:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:25:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'talos_options': ['--disable-e10s', 09:25:37 INFO - '--xperf_path', 09:25:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:25:37 INFO - 'tests': ['tp5n']}, 09:25:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:25:37 INFO - 'talos_options': ['--xperf_path', 09:25:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:25:37 INFO - 'tests': ['tp5n']}}, 09:25:37 INFO - 'talos.zip': {'path': '', 09:25:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:25:37 INFO - Running post-action listener: _resource_record_post_action 09:25:37 INFO - [mozharness: 2016-12-15 17:25:37.766379Z] Finished populate-webroot step (success) 09:25:37 INFO - [mozharness: 2016-12-15 17:25:37.766467Z] Running create-virtualenv step. 09:25:37 INFO - Running pre-action listener: _resource_record_pre_action 09:25:37 INFO - Running main action method: create_virtualenv 09:25:37 INFO - Creating virtualenv /builds/slave/test/build/venv 09:25:37 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 09:25:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:25:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:25:37 INFO - Using real prefix '/usr' 09:25:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:25:38 INFO - Installing distribute.............................................................................................................................................................................................done. 09:25:40 INFO - Installing pip.................done. 09:25:40 INFO - Return code: 0 09:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:25:40 INFO - Reading from file tmpfile_stdout 09:25:40 INFO - Output received: 09:25:40 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) 09:25:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:25:40 INFO - Using env: {'DISPLAY': ':0', 09:25:40 INFO - 'HOME': '/home/cltbld', 09:25:40 INFO - 'LANG': 'en_US.UTF-8', 09:25:40 INFO - 'LANGUAGE': 'en_US:en', 09:25:40 INFO - 'LOGNAME': 'cltbld', 09:25:40 INFO - 'MAIL': '/var/mail/cltbld', 09:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:40 INFO - 'MOZ_NO_REMOTE': '1', 09:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:40 INFO - 'NO_EM_RESTART': '1', 09:25:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:40 INFO - 'PWD': '/builds/slave/test', 09:25:40 INFO - 'SHELL': '/bin/bash', 09:25:40 INFO - 'SHLVL': '1', 09:25:40 INFO - 'TERM': 'linux', 09:25:40 INFO - 'TMOUT': '86400', 09:25:40 INFO - 'USER': 'cltbld', 09:25:40 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:40 INFO - '_': '/tools/buildbot/bin/python'} 09:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:40 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 09:25:40 INFO - Cleaning up... 09:25:40 INFO - Return code: 0 09:25:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:25:40 INFO - Using env: {'DISPLAY': ':0', 09:25:40 INFO - 'HOME': '/home/cltbld', 09:25:40 INFO - 'LANG': 'en_US.UTF-8', 09:25:40 INFO - 'LANGUAGE': 'en_US:en', 09:25:40 INFO - 'LOGNAME': 'cltbld', 09:25:40 INFO - 'MAIL': '/var/mail/cltbld', 09:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:40 INFO - 'MOZ_NO_REMOTE': '1', 09:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:40 INFO - 'NO_EM_RESTART': '1', 09:25:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:40 INFO - 'PWD': '/builds/slave/test', 09:25:40 INFO - 'SHELL': '/bin/bash', 09:25:40 INFO - 'SHLVL': '1', 09:25:40 INFO - 'TERM': 'linux', 09:25:40 INFO - 'TMOUT': '86400', 09:25:40 INFO - 'USER': 'cltbld', 09:25:40 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:40 INFO - '_': '/tools/buildbot/bin/python'} 09:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:40 INFO - Downloading/unpacking psutil>=3.1.1 09:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:25:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:25:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:25:43 INFO - Installing collected packages: psutil 09:25:43 INFO - Running setup.py install for psutil 09:25:43 INFO - building 'psutil._psutil_linux' extension 09:25:43 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 09:25:43 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 09:25:43 INFO - building 'psutil._psutil_posix' extension 09:25:43 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 09:25:43 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 09:25:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:25:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:25:43 INFO - Successfully installed psutil 09:25:43 INFO - Cleaning up... 09:25:43 INFO - Return code: 0 09:25:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:43 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:43 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 09:25:43 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 09:25:43 INFO - Using env: {'DISPLAY': ':0', 09:25:43 INFO - 'HOME': '/home/cltbld', 09:25:43 INFO - 'LANG': 'en_US.UTF-8', 09:25:43 INFO - 'LANGUAGE': 'en_US:en', 09:25:43 INFO - 'LOGNAME': 'cltbld', 09:25:43 INFO - 'MAIL': '/var/mail/cltbld', 09:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:43 INFO - 'MOZ_NO_REMOTE': '1', 09:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:43 INFO - 'NO_EM_RESTART': '1', 09:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:43 INFO - 'PWD': '/builds/slave/test', 09:25:43 INFO - 'SHELL': '/bin/bash', 09:25:43 INFO - 'SHLVL': '1', 09:25:43 INFO - 'TERM': 'linux', 09:25:43 INFO - 'TMOUT': '86400', 09:25:43 INFO - 'USER': 'cltbld', 09:25:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:43 INFO - '_': '/tools/buildbot/bin/python'} 09:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:25:45 INFO - Installing collected packages: mozsystemmonitor 09:25:45 INFO - Running setup.py install for mozsystemmonitor 09:25:45 INFO - Successfully installed mozsystemmonitor 09:25:45 INFO - Cleaning up... 09:25:46 INFO - Return code: 0 09:25:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:25:46 INFO - Using env: {'DISPLAY': ':0', 09:25:46 INFO - 'HOME': '/home/cltbld', 09:25:46 INFO - 'LANG': 'en_US.UTF-8', 09:25:46 INFO - 'LANGUAGE': 'en_US:en', 09:25:46 INFO - 'LOGNAME': 'cltbld', 09:25:46 INFO - 'MAIL': '/var/mail/cltbld', 09:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:46 INFO - 'MOZ_NO_REMOTE': '1', 09:25:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:46 INFO - 'NO_EM_RESTART': '1', 09:25:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:46 INFO - 'PWD': '/builds/slave/test', 09:25:46 INFO - 'SHELL': '/bin/bash', 09:25:46 INFO - 'SHLVL': '1', 09:25:46 INFO - 'TERM': 'linux', 09:25:46 INFO - 'TMOUT': '86400', 09:25:46 INFO - 'USER': 'cltbld', 09:25:46 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:46 INFO - '_': '/tools/buildbot/bin/python'} 09:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:46 INFO - Downloading/unpacking jsonschema==2.5.1 09:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:25:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:48 INFO - Downloading functools32-3.2.3-2.tar.gz 09:25:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:25:48 INFO - warning: no files found matching '*.txt' 09:25:48 INFO - no previously-included directories found matching 'build' 09:25:48 INFO - no previously-included directories found matching 'dist' 09:25:48 INFO - no previously-included directories found matching '.git*' 09:25:48 INFO - Installing collected packages: jsonschema, functools32 09:25:48 INFO - Running setup.py install for functools32 09:25:48 INFO - warning: no files found matching '*.txt' 09:25:48 INFO - no previously-included directories found matching 'build' 09:25:48 INFO - no previously-included directories found matching 'dist' 09:25:48 INFO - no previously-included directories found matching '.git*' 09:25:48 INFO - Successfully installed jsonschema functools32 09:25:48 INFO - Cleaning up... 09:25:48 INFO - Return code: 0 09:25:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:25:48 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')]} 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 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')]} 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:48 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 09:25:48 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 09:25:48 INFO - Using env: {'DISPLAY': ':0', 09:25:48 INFO - 'HOME': '/home/cltbld', 09:25:48 INFO - 'LANG': 'en_US.UTF-8', 09:25:48 INFO - 'LANGUAGE': 'en_US:en', 09:25:48 INFO - 'LOGNAME': 'cltbld', 09:25:48 INFO - 'MAIL': '/var/mail/cltbld', 09:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:48 INFO - 'MOZ_NO_REMOTE': '1', 09:25:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:48 INFO - 'NO_EM_RESTART': '1', 09:25:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:48 INFO - 'PWD': '/builds/slave/test', 09:25:48 INFO - 'SHELL': '/bin/bash', 09:25:48 INFO - 'SHLVL': '1', 09:25:48 INFO - 'TERM': 'linux', 09:25:48 INFO - 'TMOUT': '86400', 09:25:48 INFO - 'USER': 'cltbld', 09:25:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:48 INFO - '_': '/tools/buildbot/bin/python'} 09:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:25:48 INFO - Cleaning up... 09:25:48 INFO - Return code: 0 09:25:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:25:48 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')]} 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 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')]} 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:48 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 09:25:48 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 09:25:48 INFO - Using env: {'DISPLAY': ':0', 09:25:48 INFO - 'HOME': '/home/cltbld', 09:25:48 INFO - 'LANG': 'en_US.UTF-8', 09:25:48 INFO - 'LANGUAGE': 'en_US:en', 09:25:48 INFO - 'LOGNAME': 'cltbld', 09:25:48 INFO - 'MAIL': '/var/mail/cltbld', 09:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:48 INFO - 'MOZ_NO_REMOTE': '1', 09:25:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:48 INFO - 'NO_EM_RESTART': '1', 09:25:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:48 INFO - 'PWD': '/builds/slave/test', 09:25:48 INFO - 'SHELL': '/bin/bash', 09:25:48 INFO - 'SHLVL': '1', 09:25:48 INFO - 'TERM': 'linux', 09:25:48 INFO - 'TMOUT': '86400', 09:25:48 INFO - 'USER': 'cltbld', 09:25:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:48 INFO - '_': '/tools/buildbot/bin/python'} 09:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:49 INFO - Downloading/unpacking blobuploader==1.2.4 09:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:25:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:25:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:51 INFO - Downloading docopt-0.6.1.tar.gz 09:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:25:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:25:51 INFO - Running setup.py install for blobuploader 09:25:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:25:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:25:52 INFO - Running setup.py install for requests 09:25:52 INFO - Running setup.py install for docopt 09:25:52 INFO - Successfully installed blobuploader requests docopt 09:25:52 INFO - Cleaning up... 09:25:52 INFO - Return code: 0 09:25:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:52 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:52 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 09:25:52 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 09:25:52 INFO - Using env: {'DISPLAY': ':0', 09:25:52 INFO - 'HOME': '/home/cltbld', 09:25:52 INFO - 'LANG': 'en_US.UTF-8', 09:25:52 INFO - 'LANGUAGE': 'en_US:en', 09:25:52 INFO - 'LOGNAME': 'cltbld', 09:25:52 INFO - 'MAIL': '/var/mail/cltbld', 09:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:52 INFO - 'MOZ_NO_REMOTE': '1', 09:25:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:52 INFO - 'NO_EM_RESTART': '1', 09:25:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:52 INFO - 'PWD': '/builds/slave/test', 09:25:52 INFO - 'SHELL': '/bin/bash', 09:25:52 INFO - 'SHLVL': '1', 09:25:52 INFO - 'TERM': 'linux', 09:25:52 INFO - 'TMOUT': '86400', 09:25:52 INFO - 'USER': 'cltbld', 09:25:52 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:52 INFO - '_': '/tools/buildbot/bin/python'} 09:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:25:52 INFO - Running setup.py (path:/tmp/pip-8h22Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:25:52 INFO - Running setup.py (path:/tmp/pip-YxnBZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:25:52 INFO - Running setup.py (path:/tmp/pip-I07Eab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:25:52 INFO - Running setup.py (path:/tmp/pip-gImGpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:25:53 INFO - Running setup.py (path:/tmp/pip-87Vhvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:25:53 INFO - Running setup.py (path:/tmp/pip-UuYXZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:25:53 INFO - Running setup.py (path:/tmp/pip-HjaVE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:25:53 INFO - Running setup.py (path:/tmp/pip-hKRo0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:25:53 INFO - Running setup.py (path:/tmp/pip-7XiqaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:25:53 INFO - Running setup.py (path:/tmp/pip-nUX4_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:25:53 INFO - Running setup.py (path:/tmp/pip-D2p6n1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:25:53 INFO - Running setup.py (path:/tmp/pip-A7V4Fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:25:53 INFO - Running setup.py (path:/tmp/pip-V0Muwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:25:53 INFO - Running setup.py (path:/tmp/pip-yDs_AK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:25:53 INFO - Running setup.py (path:/tmp/pip-6cLy4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:25:53 INFO - Running setup.py (path:/tmp/pip-VqBL9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:25:53 INFO - Running setup.py (path:/tmp/pip-GFJCWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:25:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:25:53 INFO - Running setup.py install for manifestparser 09:25:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Running setup.py install for mozcrash 09:25:54 INFO - Running setup.py install for mozdebug 09:25:54 INFO - Running setup.py install for mozdevice 09:25:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Running setup.py install for mozfile 09:25:54 INFO - Running setup.py install for mozhttpd 09:25:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Running setup.py install for mozinfo 09:25:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Running setup.py install for mozInstall 09:25:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:25:54 INFO - Running setup.py install for mozleak 09:25:54 INFO - Running setup.py install for mozlog 09:25:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Running setup.py install for moznetwork 09:25:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Running setup.py install for mozprocess 09:25:55 INFO - Running setup.py install for mozprofile 09:25:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Running setup.py install for mozrunner 09:25:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Running setup.py install for mozscreenshot 09:25:55 INFO - Running setup.py install for moztest 09:25:55 INFO - Running setup.py install for mozversion 09:25:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:25:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:25:55 INFO - Cleaning up... 09:25:55 INFO - Return code: 0 09:25:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:25:55 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')]} 09:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:55 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')]} 09:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:55 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:55 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 09:25:55 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 09:25:55 INFO - Using env: {'DISPLAY': ':0', 09:25:55 INFO - 'HOME': '/home/cltbld', 09:25:55 INFO - 'LANG': 'en_US.UTF-8', 09:25:55 INFO - 'LANGUAGE': 'en_US:en', 09:25:55 INFO - 'LOGNAME': 'cltbld', 09:25:55 INFO - 'MAIL': '/var/mail/cltbld', 09:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:55 INFO - 'MOZ_NO_REMOTE': '1', 09:25:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:55 INFO - 'NO_EM_RESTART': '1', 09:25:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:55 INFO - 'PWD': '/builds/slave/test', 09:25:55 INFO - 'SHELL': '/bin/bash', 09:25:55 INFO - 'SHLVL': '1', 09:25:55 INFO - 'TERM': 'linux', 09:25:55 INFO - 'TMOUT': '86400', 09:25:55 INFO - 'USER': 'cltbld', 09:25:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:55 INFO - '_': '/tools/buildbot/bin/python'} 09:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:25:56 INFO - Running setup.py (path:/tmp/pip-I4ikk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:25:56 INFO - Running setup.py (path:/tmp/pip-FFndM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:25:56 INFO - Running setup.py (path:/tmp/pip-al2mgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:25:56 INFO - Running setup.py (path:/tmp/pip-JF0A1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:25:56 INFO - Running setup.py (path:/tmp/pip-XIUrmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:25:56 INFO - Running setup.py (path:/tmp/pip-ltChiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:25:56 INFO - Running setup.py (path:/tmp/pip-3xJpuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:25:56 INFO - Running setup.py (path:/tmp/pip-mBGEyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:25:56 INFO - Running setup.py (path:/tmp/pip-vp1khp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:25:56 INFO - Running setup.py (path:/tmp/pip-BxUP4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:25:56 INFO - Running setup.py (path:/tmp/pip-BrGkyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:25:56 INFO - Running setup.py (path:/tmp/pip-o0kkvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:25:56 INFO - Running setup.py (path:/tmp/pip-inAjjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:25:56 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)) 09:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:25:56 INFO - Running setup.py (path:/tmp/pip-EeK0r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:25:57 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)) 09:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:25:57 INFO - Running setup.py (path:/tmp/pip-qkYSBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:25:57 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)) 09:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:25:57 INFO - Running setup.py (path:/tmp/pip-tb8Sgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 09:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:25:57 INFO - Running setup.py (path:/tmp/pip-tyLRLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:25:57 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)) 09:25:57 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)) 09:25:57 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)) 09:25:57 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)) 09:25:57 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)) 09:25:57 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)) 09:25:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:59 INFO - Downloading blessings-1.6.tar.gz 09:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:25:59 INFO - Installing collected packages: blessings 09:25:59 INFO - Running setup.py install for blessings 09:25:59 INFO - Successfully installed blessings 09:25:59 INFO - Cleaning up... 09:25:59 INFO - Return code: 0 09:25:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:25:59 INFO - Reading from file tmpfile_stdout 09:25:59 INFO - Current package versions: 09:25:59 INFO - argparse == 1.2.1 09:25:59 INFO - blessings == 1.6 09:25:59 INFO - blobuploader == 1.2.4 09:25:59 INFO - docopt == 0.6.1 09:25:59 INFO - functools32 == 3.2.3-2 09:25:59 INFO - jsonschema == 2.5.1 09:25:59 INFO - manifestparser == 1.1 09:25:59 INFO - mozInstall == 1.12 09:25:59 INFO - mozcrash == 1.0 09:25:59 INFO - mozdebug == 0.1 09:25:59 INFO - mozdevice == 0.49 09:25:59 INFO - mozfile == 1.2 09:25:59 INFO - mozhttpd == 0.7 09:25:59 INFO - mozinfo == 0.9 09:25:59 INFO - mozleak == 0.1 09:25:59 INFO - mozlog == 3.4 09:25:59 INFO - moznetwork == 0.27 09:25:59 INFO - mozprocess == 0.23 09:25:59 INFO - mozprofile == 0.28 09:25:59 INFO - mozrunner == 6.13 09:25:59 INFO - mozscreenshot == 0.1 09:25:59 INFO - mozsystemmonitor == 0.3 09:25:59 INFO - moztest == 0.7 09:25:59 INFO - mozversion == 1.4 09:25:59 INFO - psutil == 3.1.1 09:25:59 INFO - requests == 1.2.3 09:25:59 INFO - wsgiref == 0.1.2 09:25:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:25:59 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')]} 09:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:59 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')]} 09:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:59 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:59 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 09:25:59 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 09:25:59 INFO - Using env: {'DISPLAY': ':0', 09:25:59 INFO - 'HOME': '/home/cltbld', 09:25:59 INFO - 'LANG': 'en_US.UTF-8', 09:25:59 INFO - 'LANGUAGE': 'en_US:en', 09:25:59 INFO - 'LOGNAME': 'cltbld', 09:25:59 INFO - 'MAIL': '/var/mail/cltbld', 09:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:59 INFO - 'MOZ_NO_REMOTE': '1', 09:25:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:59 INFO - 'NO_EM_RESTART': '1', 09:25:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:59 INFO - 'PWD': '/builds/slave/test', 09:25:59 INFO - 'SHELL': '/bin/bash', 09:25:59 INFO - 'SHLVL': '1', 09:25:59 INFO - 'TERM': 'linux', 09:25:59 INFO - 'TMOUT': '86400', 09:25:59 INFO - 'USER': 'cltbld', 09:25:59 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:59 INFO - '_': '/tools/buildbot/bin/python'} 09:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 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)) 09:25:59 INFO - Cleaning up... 09:25:59 INFO - Return code: 0 09:25:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:25:59 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')]} 09:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:59 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')]} 09:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:59 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 0x7fbe4ae78e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, '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': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', '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 09:25:59 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 09:25:59 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 09:25:59 INFO - Using env: {'DISPLAY': ':0', 09:25:59 INFO - 'HOME': '/home/cltbld', 09:25:59 INFO - 'LANG': 'en_US.UTF-8', 09:25:59 INFO - 'LANGUAGE': 'en_US:en', 09:25:59 INFO - 'LOGNAME': 'cltbld', 09:25:59 INFO - 'MAIL': '/var/mail/cltbld', 09:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:59 INFO - 'MOZ_NO_REMOTE': '1', 09:25:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:59 INFO - 'NO_EM_RESTART': '1', 09:25:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:59 INFO - 'PWD': '/builds/slave/test', 09:25:59 INFO - 'SHELL': '/bin/bash', 09:25:59 INFO - 'SHLVL': '1', 09:25:59 INFO - 'TERM': 'linux', 09:25:59 INFO - 'TMOUT': '86400', 09:25:59 INFO - 'USER': 'cltbld', 09:25:59 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:59 INFO - '_': '/tools/buildbot/bin/python'} 09:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:26:00 INFO - Cleaning up... 09:26:00 INFO - Return code: 0 09:26:00 INFO - Running post-action listener: _resource_record_post_action 09:26:00 INFO - Running post-action listener: _start_resource_monitoring 09:26:00 INFO - Starting resource monitoring. 09:26:00 INFO - [mozharness: 2016-12-15 17:26:00.208653Z] Finished create-virtualenv step (success) 09:26:00 INFO - [mozharness: 2016-12-15 17:26:00.209168Z] Running install step. 09:26:00 INFO - Running pre-action listener: _resource_record_pre_action 09:26:00 INFO - Running main action method: install 09:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:00 INFO - Reading from file tmpfile_stdout 09:26:00 INFO - Detecting whether we're running mozinstall >=1.0... 09:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:26:00 INFO - Reading from file tmpfile_stdout 09:26:00 INFO - Output received: 09:26:00 INFO - Usage: mozinstall [options] installer 09:26:00 INFO - Options: 09:26:00 INFO - -h, --help show this help message and exit 09:26:00 INFO - -d DEST, --destination=DEST 09:26:00 INFO - Directory to install application into. [default: 09:26:00 INFO - "/builds/slave/test"] 09:26:00 INFO - --app=APP Application being installed. [default: firefox] 09:26:00 INFO - mkdir: /builds/slave/test/build/application 09:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:26:16 INFO - Reading from file tmpfile_stdout 09:26:16 INFO - Output received: 09:26:16 INFO - /builds/slave/test/build/application/firefox/firefox 09:26:16 INFO - Running post-action listener: _resource_record_post_action 09:26:16 INFO - [mozharness: 2016-12-15 17:26:16.767391Z] Finished install step (success) 09:26:16 INFO - [mozharness: 2016-12-15 17:26:16.767632Z] Running run-tests step. 09:26:16 INFO - Running pre-action listener: _resource_record_pre_action 09:26:16 INFO - Running main action method: run_tests 09:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:26:16 INFO - Python 2.7.3 09:26:16 INFO - Return code: 0 09:26:16 INFO - grabbing minidump binary from tooltool 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 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 0x268cf20>, '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 0x25f9c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f9f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:26:16 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 09:26:16 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 09:26:16 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 09:26:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:26:16 INFO - Return code: 0 09:26:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:26:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:26:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:26:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:26:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 09:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 09:26:16 INFO - Using env: {'DISPLAY': ':0', 09:26:16 INFO - 'HOME': '/home/cltbld', 09:26:16 INFO - 'LANG': 'en_US.UTF-8', 09:26:16 INFO - 'LANGUAGE': 'en_US:en', 09:26:16 INFO - 'LOGNAME': 'cltbld', 09:26:16 INFO - 'MAIL': '/var/mail/cltbld', 09:26:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:16 INFO - 'MOZ_NO_REMOTE': '1', 09:26:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:16 INFO - 'NO_EM_RESTART': '1', 09:26:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:16 INFO - 'PWD': '/builds/slave/test', 09:26:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:16 INFO - 'SHELL': '/bin/bash', 09:26:16 INFO - 'SHLVL': '1', 09:26:16 INFO - 'TERM': 'linux', 09:26:16 INFO - 'TMOUT': '86400', 09:26:16 INFO - 'USER': 'cltbld', 09:26:16 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1481822721.17288-795910151', 09:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:16 INFO - '_': '/tools/buildbot/bin/python'} 09:26:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 09:26:17 INFO - mozversion application_buildid: 20161215074604 09:26:17 INFO - mozversion application_changeset: 05f6c6e2e3d45128c268b6681980a91febabfab2 09:26:17 INFO - mozversion application_display_name: Nightly 09:26:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:26:17 INFO - mozversion application_name: Firefox 09:26:17 INFO - mozversion application_remotingname: firefox 09:26:17 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:26:17 INFO - mozversion application_vendor: Mozilla 09:26:17 INFO - mozversion application_version: 53.0a1 09:26:17 INFO - mozversion platform_buildid: 20161215074604 09:26:17 INFO - mozversion platform_changeset: 05f6c6e2e3d45128c268b6681980a91febabfab2 09:26:17 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:26:17 INFO - mozversion platform_version: 53.0a1 09:26:17 INFO - using testdate: 1481822777 09:26:17 INFO - actual date: 1481822777 09:26:17 INFO - starting webserver on 'localhost:54967' 09:26:17 INFO - SUITE-START | Running 2 tests 09:26:17 INFO - TEST-START | dromaeo_css 09:26:17 INFO - operating with platform_type : linux_ 09:26:17 INFO - Initialising browser for dromaeo_css test... 09:26:17 INFO - TEST-INFO | started process 10774 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphGgVMf/profile http://localhost:54967/getInfo.html) 09:26:21 INFO - PROCESS | 10774 | __metrics Screen width/height:1600/1200 09:26:21 INFO - PROCESS | 10774 | colorDepth:24 09:26:21 INFO - PROCESS | 10774 | Browser inner width/height: 1024/697 09:26:21 INFO - PROCESS | 10774 | __metrics 09:26:22 INFO - PROCESS | 10774 | 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:26:22 INFO - PROCESS | 10774 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:23 INFO - PROCESS | 10774 | console.error: PushService: 09:26:23 INFO - PROCESS | 10774 | stateChangeProcessEnqueue: Error transitioning state 09:26:23 INFO - PROCESS | 10774 | UnknownError 09:26:23 INFO - TEST-INFO | 10774: exit 0 09:26:23 INFO - Browser initialized. 09:26:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:26:23 INFO - TEST-INFO | started process 10856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphGgVMf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:36 INFO - PROCESS | 10856 | RSS: Main: 173129728 09:26:36 INFO - PROCESS | 10856 | 09:27:27 INFO - PROCESS | 10856 | 1481822847148 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:29:41 INFO - PROCESS | 10856 | Cycle 1(1): loaded http://localhost:54967/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54967/tests/dromaeo/cssquery-ext.html) 09:29:43 INFO - PROCESS | 10856 | RSS: Main: 321089536 09:29:43 INFO - PROCESS | 10856 | 09:32:45 INFO - PROCESS | 10856 | Cycle 1(1): loaded http://localhost:54967/tests/dromaeo/cssquery-ext.html (next: http://localhost:54967/tests/dromaeo/cssquery-jquery.html) 09:32:46 INFO - PROCESS | 10856 | RSS: Main: 277749760 09:32:46 INFO - PROCESS | 10856 | 09:35:48 INFO - PROCESS | 10856 | Cycle 1(1): loaded http://localhost:54967/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54967/tests/dromaeo/cssquery-mootools.html) 09:35:49 INFO - PROCESS | 10856 | RSS: Main: 217214976 09:35:49 INFO - PROCESS | 10856 | 09:38:51 INFO - PROCESS | 10856 | Cycle 1(1): loaded http://localhost:54967/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54967/tests/dromaeo/cssquery-prototype.html) 09:38:52 INFO - PROCESS | 10856 | RSS: Main: 225476608 09:38:52 INFO - PROCESS | 10856 | 09:41:55 INFO - PROCESS | 10856 | Cycle 1(1): loaded http://localhost:54967/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54967/tests/dromaeo/cssquery-yui.html) 09:41:56 INFO - PROCESS | 10856 | RSS: Main: 218865664 09:41:56 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | Cycle 1(1): loaded http://localhost:54967/tests/dromaeo/cssquery-yui.html (next: http://localhost:54967/tests/dromaeo/cssquery-dojo.html) 09:44:59 INFO - PROCESS | 10856 | RSS: Main: 312508416 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | __start_tp_report 09:44:59 INFO - PROCESS | 10856 | _x_x_mozilla_page_load 09:44:59 INFO - PROCESS | 10856 | _x_x_mozilla_page_load_details 09:44:59 INFO - PROCESS | 10856 | |i|pagename|runs| 09:44:59 INFO - PROCESS | 10856 | |0;dojo.html;2580.380708629929;2608;2624.199619116169;2636.644053052837;2654.9469010619787;4547.454305483347;4593.655475839309;4705.200115980283;4708.152532544141;4708.929366059508;4627.537246275371;4628.930566041512;4637.9072418551605;4675.181843177447;4708.98756767295;4234.470691163605;4281.571842815724;4294.527601963781;4320.675949303799;4394.912101757963;4260.552641972595;4291.334843099325;4301.698881078326;4317.43873296471;4354.934675979863;4122.051928056534;4268.381084742695;4281.871543853689;4286.0999190170105;4362.1275744851;4131.5042194936495;4196.7272127311935;4197.790110494463;4211.515675697304;4212.347086201644;4149.564295748964;4184.832606695729;4219.915601687949;4259.914801703949;4273.636740877029;4566.246569316058;4579.084183163347;4592.081583683277;4594.74728889911;4624.1445332613575;4897.926531102036;4901.1177987962155;4902.509749025105;4902.8774280643265;4956.107900577895;4784.832530861403;4823.493533179036;4828;4879.121758083544;4937.555619994182;4583.9541604583865;4594.517575654575;4594.954050459452;4632;4641.74470404131;5014.749262536859;5021.196608542616;5022.748862556858;5026.547610715053;5070.340855688811;4623.676342656049;4659.254519276899;4812.855614331575;4814.470408255089;4867.415910090812;4814;4888.804447822118;4912.484189160158;4924.433690125681;4981.32752078472;4897.363342765418;4906.705597664151;4906.975465122651;4911.754412279443;4923.876903077452;4878.585320247747;4893.388326459192;4902.803887844518;4904.229749485825;4939.357883475197;4202.411662367271;4229.91540169201;4231.682623803227;4236.406903033578;4265.680073994463;3720.795356255378;3733.6079711629886;3758.567764707094;3771.7548359356556;3789.5263092113487;4232.492100947844;4233.661307095425;4255.297875850512;4278.101598664253;4303.74177549348;4835.95164048362;4880.1215781159735;4883.731394773251;4917.385326834145;4932.161532539477;4255.744655320691;4291.399204111427;4308.353746937922;4320.503142138631;4334.609885110292;4467.687261891637;4473.217186992263;4483.865484035484;4488.506264310923;4552.521985191574;4477.417935668343;4609.262517997105;4616.930746038879;4620.029793743352;4650.279206722963;4772.3557319761385;4857.198562237332;4891.413030436371;4895.363602731622;4898.53463920927;4568.543145685405;4579.702319349231;4587.77061146948;4610.031893302444;4615.399998000373;4834.661573689808;4841.201201801663;4841.491643377394;4844.0554091951735;4891.5353041461;4742.952570474251;4760.166970780169;4773.164696178085;4822.758862056953;4908.631852610997;4647.86056418308;4673;4677.181493238738;4728.1252968201;4731.597814185695;4636.374089498009;4678.555537223959;4682.625389968863;4722.5513576210205;4727.905441891074;4609.21643320643;4614.169449498988;4633.2818413146015;4645.210314246654;4693.953060469352;4768.356271903387;4784.258439941815;4800.4479484859685;4836.806527738851;4841.830535931225;4619.86140415788;4654.4414670239785;4691.695039822401;4746.952530474651;4759.048190362011 09:44:59 INFO - PROCESS | 10856 | |1;ext.html;509.4497942222399;536.9006733754255;553.8061678412556;555.6360583817599;570.3554982869357;27865.164045078654;40451.79774101133;42899.14201715968;43059.56940430595;43073.35389969153;40040.799796001054;40560.18879622406;40608;40630.79684601573;40642.59357406425;7629;7782.299593036626;7812.3359514441345;7813.179616140309;7814.648340824677;7467.402607791364;7654.158042615308;9766.169875560587;10002.299839011272;10026.9498652507;10054.195664346835;10099.29304948654;10120.342177758459;10126.291159618793;10127.544260508257;10031;10101.292909496338;10101.393916364968;10109.292349535463;10118.13995810357;9934.304598678023;9991.200703943667;9997.650082247086;9999.700008999669;10003.499825008721;36182;36570.81714591436;36607.81696091529;36651.4502282466;36665.633343666486;2965.5996440480476;3071.8156910585435;3164.7626428017993;3557.1462848916076;3616.385214513539;28289.43421131566;28320.291992700142;28720;29193;29414.70585294141;3098.659147493774;3148.8740450381956;3190.6649801770945;3233.4826427771677;3265.591801024872;235356;235458;235500;236187.99999999828;236231;52812;53272.73363633157;53319.466805332224;53330.73334633302;53380.73309633427;55452.44547554554;55558.444415556136;55918.440815592134;55937.16094258589;56046;13736;14186.432542698383;14262.786058209134;14276.0727301815;14280.42878284857;9318;10692.465376731132;10846.18653600983;10863;10873.184511161695;14570.708585828224;16478.176091195393;16826.326946922232;17099.401520946707;17663.20515576802;5984.31180414258;6000.849978750566;6106.145139680449;6155.630662160284;6196.690165491707;773.2036002917005;876.4565969099199;896.6099746610246;1005.2008653120787;1018.4754851251612;26418;27595.826479955063;28494.430111397654;28588.857055714587;28633.713662863105;949.5632009275672;968.5738275158908;968.9612415503301;978.8042391521724;1017.4912543728136;13562.59312220635;13925.791113133311;14350.354234059492;14522.128672279698;15168.393264269527;349131;349682;352370;353202;353930;42974.28102875913;43044.56955430468;43116.78441607772;43191.35212971808;43342.56657433449;1052.079430498314;1088.804015277226;1098.9945050274698;1107.335598640825;1165.7785020846013;23052.193173239528;26089.347766305236;26301;26365.4726905457;26606.201813945612;36812.63187368092;37140.628593713715;37169.25661486701;37246;37250.254994899406;280321;280336;281269;281485;284562;22158.335249942527;23130.884345578164;23174;24252.272431827074;24411.633825492278;36351.091222719646;36423.63576364202;36454.8177259112;36609;37541.43687844739;31470.842645786626;31543;31576.684233158292;31578.473722101717;32605;30862.69137308688;31480.37039259248;31657.525137122502;31711.682883170874;31829.20426989251;31280;32006.6799332013;32120.35759284848;32245.67754322427;32446.675533244364;26663.466730665667;28469.572956406086;28703.42593148167;28901.42197156087;29101.12696619105;22696.43258918474;22884.31347059591;22994.310170694906;23218.722970236122;23230.53538929178 09:44:59 INFO - PROCESS | 10856 | |2;jquery.html;338.50240146983924;343.4624812168957;361.1675088920039;361.56973201889747;381.7856432690395;12413.379331033446;14043.929780351096;14264;16357.509274721777;16385.180740962933;15067.849321506808;15237.390504379839;15248.313825877834;15319.846801531981;15335.84664153358;4291.249031419505;4501.414816073922;4810.302506136608;5103.132467480537;5105.770240339175;4969.8012079516775;5069.188929771238;5100.489951004888;5107.284980102789;5113.2330150477355;4849.321095046696;5083.491650834905;5085.3389059422225;5087.084324821531;5088.54203121719;4992.40091189056;5001.1248031594505;5003.424606170301;5021.774020169101;5044.873878153076;4314.676399270036;4464.732116073046;4471.396361491222;4473.865784026484;4477.932831007537;18937.62124757497;19291.22835086595;19386.127624256944;19442.22231110754;19465.318713844947;1772.1733834533898;1986.145957238387;2019.4446527204989;2058.763242227148;2502.036715864387;12157.939210303892;12665;12818.87181128186;13219.405126769223;13312.667183320398;1680.2522877319598;1754.429810311654;1777.6444711057711;1780.3234770787149;1780.5370603643016;65359.67320163369;69482.65258673725;69886;70001;70382;9382.62469501226;9705.029497050238;9718.659846905324;9793.314467987173;9807.411555306562;12973.481060757653;13127.803082953764;13132.212067275803;13134.540291089766;13227.206367617973;6755.695993680296;6804.6257455840405;6868.278830722802;6960.051185668021;6963.1644202696;6338.207724034496;6397.808065758035;6478.125453063871;6514.674266286666;6576.86846263072;3744.719146064056;3895.7467764595212;4005.599440055971;4196.328587425997;4244.151169766059;1766.4523997560798;1777.111444277861;1801.4055361730598;1819.3632228720107;1830.6704793137362;766.6051983228642;798.2496453333847;811.2941740685524;890.2062344375958;906.7959709065408;15497.30262138184;16043.197840107949;16080.115593642462;16425.83574164243;16759;855.9914400855912;870.6256309786726;875.7722992021993;882.5322579033123;927.3925578745932;8655.6970506032;9216.262698984065;9370.62517499306;9405;9557.187639050619;319260.6332715033;322218;322885;324266;329783;72235;72421.63789181126;73744.63127684327;75834.3457978968;76813.61593191998;864.5331520978601;898.9865152022725;911.0752586125088;914.0311270053631;925.9305552083621;14313.57059288223;14363.928180359031;14451.638709032359;14568.34442450071;14926.477573284883;34075.14812129716;34318.14204644904;35518.289634207686;35636.287274254886;37134.8143259282;46146.38536146359;48245;50290.2456463146;52233.7388313056;54068;6572.868542629216;7027.121609798775;7337.926620733724;7432.442566807403;7434.553926764412;8552.957235213784;10087;11125.721856953318;11568.768624627628;11705.180637355645;9333.346665733334;9981.800363992825;10023.649172278936;10094.545745441326;10102.141317988053;9663.13031827139;9866;10184.592616295266;10200.897991019994;10243.282970192313;10342.482875856327;10389.220808439548;10560.841587376042;10598.841017384591;10841.265397536356;6178.474829639531;6279.340669229665;6408.807735767934;6540.215174179129;7045.471589630695;7158.677859496439;9581.329306948446;10063.194944404577;10329;10656.84014739795 09:44:59 INFO - PROCESS | 10856 | |3;mootools.html;1310.1287643716926;1334.2528184216835;1426.9357878895448;1623.7807895934045;1669.8246684098162;5918.585699001072;6095.756169753205;6325.620462772231;6730.091437655915;6731.3268673132725;6222.657753823538;6459.580127291722;6474.158359413282;6493.285738568754;6650.102236198112;1987.0561483295428;2044.631966246075;2054.2296638760463;2055.085486958304;2055.5580550181708;1779.5729025034027;1835.0182652280967;2006.829419499337;2099.055425058722;2108.57828434313;2011.7083022961663;2034.725312082872;2035.8269547088441;2039.6022775558772;2041.050911326233;1969.9212031518723;1971.4775584469983;1973.881567105971;1974.3385965701507;2014.6776515757556;2037.1749441476227;2039.367795983235;2077.6571865642304;2078.9480262993393;2080.06397121296;6806.693698783567;6858.177018757731;6858.794236172923;6861.039454476378;6865.862682746367;4039.2931237033695;4164.5210800757695;4171.416001759756;4177.665786737054;4187.330027195664;2589.1196993022463;2741.8354898705943;2819.478396496634;2821.50623640862;2828.0950095969297;774.7559518751632;815.975520734379;845.4969293270494;848.9023762267357;849.7365816596813;8633;8865.33509986747;8873;8880.378373513857;8881.73354799357;3585.103724068983;3764.265968136187;3794.051487128218;3848.6536211741077;3877.1664092219744;7401.555906645618;7465.58939258346;7470.962645186739;7477.065366829146;7484.0270764800225;4136.276151673445;4215.451991241181;4219.957800421957;4223.450951376301;4226.619604235633;3798.7254407809287;3804.8858534243464;3858.054776579756;3859.1895701902913;3871.090293780959;3136.8274744888795;3149.401613693391;3220.404225218342;3230.03099070278;3231.4021905947475;1027.4913917610681;1067.1889364083197;1070.4433694478828;1071.185898716965;1074.6883403812963;789.8341348316918;792.3700657976922;798.4171554765054;824.674253669797;826.9586520673943;4543.477500087466;4578.862634120982;4607.470140933836;4763.309320148628;4847;371.21858487883156;373.534948988511;378.49092969955365;378.78977167671655;381.0682880357499;3310.4344133296886;3337.3992681317595;3342.9164270892934;3344.6989770920245;3373.1735724747596;7233.095863017123;7514.173440921494;7725.690972361042;7725.806854828562;7766.37868970481;7636.770896873103;7694.692212311556;7696;7700.268474494914;7700.614969251515;6067.787627433018;6087.939120608826;6170.598911070766;6192.721327540272;6203.96898015516;4477.216487114677;4491.191585514508;4541.977290113528;4630.398048253705;4714.363560919232;6999.370056694922;7129.750458733919;7154.570725756471;7154.606496642735;7214.74748383804;7247.601381923834;7331.156916954618;7342.70629174827;7353.632318383952;7361.1166659999;5550.4449555044175;5669.801556945487;5705.2297939779255;5705.857353566194;5717.42825717425;6221;6321.399467050623;6323.652199129092;6325.525585581192;6328.335524770017;5712.514436272963;5840;5881.382454842351;5930.140129681258;5993.550483713651;5642.294713160855;5648.293963254593;5720.227769251264;5726.914096288642;5857.414258574108;5334.706591137525;5475.972620136874;5477.835664930059;5478.397376288684;5482.671039737628;4108.198901214354;4125.50493940729;4208.2004419161485;4228.1966426378885;4379.0366119453665;4086.9795651022746;4125.071858831739;4191.497020357577;4225.112726327506;4327.199468098412 09:44:59 INFO - PROCESS | 10856 | |4;prototype.html;324.7840186276126;329.6077667575585;330.20585491892007;330.66602731241454;332.497928128526;4737.692050016746;4779.904401911964;4819.397575303087;4821.240247309733;4853.902921941563;4770.7376094314795;4777.928331075037;4786.234202527597;4790.976045119778;4798.47216806151;3589.6051434342203;3707.5725845549523;3715.5541335039698;3789.696824254067;3839.59684233155;3767.5478942526797;3767.905802354949;3801.581825999138;3806.448065030569;3811.866584669537;3762.9059273518105;3767.3407153747985;3775.5086740737393;3778.905527361824;3783.772973621578;3577.4097273949897;3650.1787097903025;3692.1692619160744;3710.666040056381;3772.019274988509;3343.916402089943;3347.4142025145748;3350.3634309481363;3365.4110530657035;3367.3938691035605;4644.210484217691;4669.439667239919;4676.812927482896;4687;4694.201985662446;4002.7398219115958;4302.827886884521;4306.5262821089655;4314;4330.17726631939;4930.161872481687;5013.273075404082;5043.949560504385;5045.571126454256;5073.949260507385;3501.1772233525103;3523.277728065766;3529.8058606776617;3534.9748572913822;3535.982320088409;5240.558846317255;5270.894582108336;5281.762320695578;5286.207068939651;5299.788008479656;3373.9325213495595;3484.738644601665;3542.8405721742583;3546.8226588670464;3559.110222444389;3581.7492775505466;3582.1223800169128;3586.6413358664445;3599.5500562429697;3608.6752192302292;4161.9375709364385;4173.582641735802;4187;4187.832486700498;4225.9577404226175;4882.292067650171;5010.674306170087;5047.4952504749235;5065.493450654979;5077.791160845044;1227.6010296653624;1240.8584102625607;1241.1374095004066;1242.1801610936739;1246.3548116486331;806.1696452653777;820.885076089348;840.932725381968;843.409613270713;848.6393282854762;4291.0559676871035;4365.956340436618;4410.933835992462;4448.088141930896;4507.549245075466;5141.305923700327;5182.663126896739;5196.76614552346;5227.320448341691;5244.632875698664;4457.500861823518;4458.331250312414;4464.642828573771;4472.463304403493;4483.618892394118;2612.7648511633665;2688.5967104934416;2698;2722.632444619951;2733.753962143377;4791.209450440708;4806.182948898696;4811.158047341702;4814.844437335014;4825.855224343275;4994.375703037121;5178.715170665574;5187.213334266007;5291.470852914755;5296.655717378435;4719.433667959866;4728;4768.117898188846;4786.64100192487;4791.760411979457;4964.503549645151;4995.8751031223055;5055.9494405055475;5065.797368105234;5074.238864170404;4706.317583950239;4957.801687932442;4962.181240095344;4993.500649934977;4996.3005179274205;4720.763961801965;4729.179265982691;4739.7867095981455;4741.170295198395;4750.216214324598;3697.5378077740284;3698.704103671646;3706.7405281631104;3709.10981364476;3783.2244389900557;4993.650444468853;5035.84892453227;5064.265681476091;5068.569171620454;5087.23691446286;4766.571008609242;5055.7472126392795;5067.847964561069;5086.109126518142;5092.465291144376;5037.496250374933;5044.344235249394;5052.236084117209;5087.669301495391;5111.674188244414;4829.855104346875;4964.751762411793;4970.6023518117745;4978.60171186297;5007.273945277987;4836.443808961873;4949.698390248552;5026.346574945233;5055.115354812967;5082.949170508247;4824.662273640812;4928.975355123202;4946.480619534897;4967.900641987212;4989.176785830297 09:44:59 INFO - PROCESS | 10856 | |5;yui.html;637.7927173668559;721.577876941989;726.4370113162302;727.5961841178146;729.51122747759;12253.816192757105;13593.252371119597;13602.727945441085;13609.115407498506;13648.044636875422;12519.937400313009;12617.55838545651;12623.179493332927;12631.305278209718;12636.178648387848;619.0590302739832;636.9968150159243;752.4018405367718;756.9356604688594;772.3435080181856;773.2615352338503;782.104490358542;783.4554984285924;783.4750717019582;783.9529628222297;830.5764060329245;832.7293629570383;832.7918020494876;833.2375876073386;834.5243211369522;649.2792999770274;668.004673037176;795.2127393880018;809.4091313341294;826.3760860550276;605.9787907423218;606.2421972534332;606.769427617507;607.2409488139825;607.2409488139825;13175.14361566495;13241.205527668371;13252.403641836141;13259.403326850315;13269.734605307936;11376.544938202462;11831.171817972745;11921.165518413714;11923.403829808562;12578.55975040869;11660.12549058824;11830.704232394259;11835.408229588485;11951.760964780742;12115.575954841539;4035.3341698619693;4114.5679703630985;4117.464729585135;4157.937630935538;4295.506016808075;64979.350206498275;65397;66135.3386466134;66264.66867665679;67068.3293167067;9937.205023598239;9979.650712225037;10064.396136231702;10255.692329230134;10301;17765.467035988942;17774.111294435228;17782.37761678335;17782.644347113244;17785.911070444567;6056.666883321372;6421.550491465552;6574.211094668671;6683.899741503881;6946.0622815920215;3658.1037645776955;3822.1973385589336;3825.2349530094093;3860.8841734748003;3905.941410878839;2592.157548796649;2662.0150544298344;2665.4802313549058;2666.719994400599;2678.8660566971575;482.95411935866036;487.28612582566;487.8780304923769;489.7404375680895;493.44980346912934;484.73000738065787;514.948505149482;564.6753116957766;573.3091624592356;603.5322624965687;7909.344818454832;8201.179882011871;8281.751547453587;8314.750557483285;8401.663933442716;487.90729761345233;514.4495389932811;558.2714557502417;613.9662318572522;621.9533534984894;3955.545112312121;4057.3711074783455;4078.1435898461655;4157.729747566398;4268.530461649216;49833;51706;52363.738181309614;58988.41011589829;59306.70346648325;50933.74533127311;51144.23283650748;51280;51598.74200629048;52230.738846305525;45890.311645325346;46129.235445265294;47205.76397118061;47561.438940990956;48166.27750583744;8525.701600444203;9021.413608115452;9048.140426659456;9129.132732390412;9372.297077719335;33313.333733325686;34376.312473750884;34564.82717586396;34757.65242347544;35102.297954041285;39014.4147837777;39463;40921;41144.588554114074;42662.080206390165;10837.945810270898;10889.618863339745;11215.60745373908;11306.773864522827;11833.408329583313;12211.69470763238;12503.124781265224;12524.123311368481;12658.620241392773;12977.026722995624;11400;11525;11749.361555961332;11952.76094478088;12125.454354554242;12170;12173.452194651085;12417.441215145522;12664.366781661065;12675.366231688195;10820.729481762704;10858.511366988347;10978.505967231653;11046.447677615926;11082.667519974413;9576.329656923948;9587.089226523469;9638.518074096408;9700.57974761601;9985.45080020606;9382.718518444457;9911.182280218483;10062.195024398177;10064.547095380873;10114.59541618327 09:44:59 INFO - PROCESS | 10856 | __end_tp_report 09:44:59 INFO - PROCESS | 10856 | __start_cc_report 09:44:59 INFO - PROCESS | 10856 | _x_x_mozilla_cycle_collect,1591 09:44:59 INFO - PROCESS | 10856 | __end_cc_report 09:44:59 INFO - PROCESS | 10856 | __startTimestamp1481823899560__endTimestamp 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | ------- Summary: start ------- 09:44:59 INFO - PROCESS | 10856 | Number of tests: 6 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | [#0] dojo.html Cycles:165 Average:4540.48 Median:4636.37 stddev:445.37 (9.6%) stddev-sans-first:419.36 09:44:59 INFO - PROCESS | 10856 | Values: 2580.4 2608.0 2624.2 2636.6 2654.9 4547.5 4593.7 4705.2 4708.2 4708.9 4627.5 4628.9 4637.9 4675.2 4709.0 4234.5 4281.6 4294.5 4320.7 4394.9 4260.6 4291.3 4301.7 4317.4 4354.9 4122.1 4268.4 4281.9 4286.1 4362.1 4131.5 4196.7 4197.8 4211.5 4212.3 4149.6 4184.8 4219.9 4259.9 4273.6 4566.2 4579.1 4592.1 4594.7 4624.1 4897.9 4901.1 4902.5 4902.9 4956.1 4784.8 4823.5 4828.0 4879.1 4937.6 4584.0 4594.5 4595.0 4632.0 4641.7 5014.7 5021.2 5022.7 5026.5 5070.3 4623.7 4659.3 4812.9 4814.5 4867.4 4814.0 4888.8 4912.5 4924.4 4981.3 4897.4 4906.7 4907.0 4911.8 4923.9 4878.6 4893.4 4902.8 4904.2 4939.4 4202.4 4229.9 4231.7 4236.4 4265.7 3720.8 3733.6 3758.6 3771.8 3789.5 4232.5 4233.7 4255.3 4278.1 4303.7 4836.0 4880.1 4883.7 4917.4 4932.2 4255.7 4291.4 4308.4 4320.5 4334.6 4467.7 4473.2 4483.9 4488.5 4552.5 4477.4 4609.3 4616.9 4620.0 4650.3 4772.4 4857.2 4891.4 4895.4 4898.5 4568.5 4579.7 4587.8 4610.0 4615.4 4834.7 4841.2 4841.5 4844.1 4891.5 4743.0 4760.2 4773.2 4822.8 4908.6 4647.9 4673.0 4677.2 4728.1 4731.6 4636.4 4678.6 4682.6 4722.6 4727.9 4609.2 4614.2 4633.3 4645.2 4694.0 4768.4 4784.3 4800.4 4836.8 4841.8 4619.9 4654.4 4691.7 4747.0 4759.0 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | [#1] ext.html Cycles:180 Average:43957.49 Median:31577.58 stddev:77054.84 (244.0%) stddev-sans-first:77201.95 09:44:59 INFO - PROCESS | 10856 | Values: 509.4 536.9 553.8 555.6 570.4 27865.2 40451.8 42899.1 43059.6 43073.4 40040.8 40560.2 40608.0 40630.8 40642.6 7629.0 7782.3 7812.3 7813.2 7814.6 7467.4 7654.2 9766.2 10002.3 10026.9 10054.2 10099.3 10120.3 10126.3 10127.5 10031.0 10101.3 10101.4 10109.3 10118.1 9934.3 9991.2 9997.7 9999.7 10003.5 36182.0 36570.8 36607.8 36651.5 36665.6 2965.6 3071.8 3164.8 3557.1 3616.4 28289.4 28320.3 28720.0 29193.0 29414.7 3098.7 3148.9 3190.7 3233.5 3265.6 235356.0 235458.0 235500.0 236188.0 236231.0 52812.0 53272.7 53319.5 53330.7 53380.7 55452.4 55558.4 55918.4 55937.2 56046.0 13736.0 14186.4 14262.8 14276.1 14280.4 9318.0 10692.5 10846.2 10863.0 10873.2 14570.7 16478.2 16826.3 17099.4 17663.2 5984.3 6000.8 6106.1 6155.6 6196.7 773.2 876.5 896.6 1005.2 1018.5 26418.0 27595.8 28494.4 28588.9 28633.7 949.6 968.6 969.0 978.8 1017.5 13562.6 13925.8 14350.4 14522.1 15168.4 349131.0 349682.0 352370.0 353202.0 353930.0 42974.3 43044.6 43116.8 43191.4 43342.6 1052.1 1088.8 1099.0 1107.3 1165.8 23052.2 26089.3 26301.0 26365.5 26606.2 36812.6 37140.6 37169.3 37246.0 37250.3 280321.0 280336.0 281269.0 281485.0 284562.0 22158.3 23130.9 23174.0 24252.3 24411.6 36351.1 36423.6 36454.8 36609.0 37541.4 31470.8 31543.0 31576.7 31578.5 32605.0 30862.7 31480.4 31657.5 31711.7 31829.2 31280.0 32006.7 32120.4 32245.7 32446.7 26663.5 28469.6 28703.4 28901.4 29101.1 22696.4 22884.3 22994.3 23218.7 23230.5 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | [#2] jquery.html Cycles:180 Average:21995.15 Median:4468.06 stddev:53919.26 (1206.8%) stddev-sans-first:54046.00 09:44:59 INFO - PROCESS | 10856 | Values: 338.5 343.5 361.2 361.6 381.8 12413.4 14043.9 14264.0 16357.5 16385.2 15067.8 15237.4 15248.3 15319.8 15335.8 4291.2 4501.4 4810.3 5103.1 5105.8 4969.8 5069.2 5100.5 5107.3 5113.2 4849.3 5083.5 5085.3 5087.1 5088.5 4992.4 5001.1 5003.4 5021.8 5044.9 4314.7 4464.7 4471.4 4473.9 4477.9 18937.6 19291.2 19386.1 19442.2 19465.3 1772.2 1986.1 2019.4 2058.8 2502.0 12157.9 12665.0 12818.9 13219.4 13312.7 1680.3 1754.4 1777.6 1780.3 1780.5 65359.7 69482.7 69886.0 70001.0 70382.0 9382.6 9705.0 9718.7 9793.3 9807.4 12973.5 13127.8 13132.2 13134.5 13227.2 6755.7 6804.6 6868.3 6960.1 6963.2 6338.2 6397.8 6478.1 6514.7 6576.9 3744.7 3895.7 4005.6 4196.3 4244.2 1766.5 1777.1 1801.4 1819.4 1830.7 766.6 798.2 811.3 890.2 906.8 15497.3 16043.2 16080.1 16425.8 16759.0 856.0 870.6 875.8 882.5 927.4 8655.7 9216.3 9370.6 9405.0 9557.2 319260.6 322218.0 322885.0 324266.0 329783.0 72235.0 72421.6 73744.6 75834.3 76813.6 864.5 899.0 911.1 914.0 925.9 14313.6 14363.9 14451.6 14568.3 14926.5 34075.1 34318.1 35518.3 35636.3 37134.8 46146.4 48245.0 50290.2 52233.7 54068.0 6572.9 7027.1 7337.9 7432.4 7434.6 8553.0 10087.0 11125.7 11568.8 11705.2 9333.3 9981.8 10023.6 10094.5 10102.1 9663.1 9866.0 10184.6 10200.9 10243.3 10342.5 10389.2 10560.8 10598.8 10841.3 6178.5 6279.3 6408.8 6540.2 7045.5 7158.7 9581.3 10063.2 10329.0 10656.8 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | [#3] mootools.html Cycles:180 Average:4399.41 Median:4618.93 stddev:2303.27 (49.9%) stddev-sans-first:2298.03 09:44:59 INFO - PROCESS | 10856 | Values: 1310.1 1334.3 1426.9 1623.8 1669.8 5918.6 6095.8 6325.6 6730.1 6731.3 6222.7 6459.6 6474.2 6493.3 6650.1 1987.1 2044.6 2054.2 2055.1 2055.6 1779.6 1835.0 2006.8 2099.1 2108.6 2011.7 2034.7 2035.8 2039.6 2041.1 1969.9 1971.5 1973.9 1974.3 2014.7 2037.2 2039.4 2077.7 2078.9 2080.1 6806.7 6858.2 6858.8 6861.0 6865.9 4039.3 4164.5 4171.4 4177.7 4187.3 2589.1 2741.8 2819.5 2821.5 2828.1 774.8 816.0 845.5 848.9 849.7 8633.0 8865.3 8873.0 8880.4 8881.7 3585.1 3764.3 3794.1 3848.7 3877.2 7401.6 7465.6 7471.0 7477.1 7484.0 4136.3 4215.5 4220.0 4223.5 4226.6 3798.7 3804.9 3858.1 3859.2 3871.1 3136.8 3149.4 3220.4 3230.0 3231.4 1027.5 1067.2 1070.4 1071.2 1074.7 789.8 792.4 798.4 824.7 827.0 4543.5 4578.9 4607.5 4763.3 4847.0 371.2 373.5 378.5 378.8 381.1 3310.4 3337.4 3342.9 3344.7 3373.2 7233.1 7514.2 7725.7 7725.8 7766.4 7636.8 7694.7 7696.0 7700.3 7700.6 6067.8 6087.9 6170.6 6192.7 6204.0 4477.2 4491.2 4542.0 4630.4 4714.4 6999.4 7129.8 7154.6 7154.6 7214.7 7247.6 7331.2 7342.7 7353.6 7361.1 5550.4 5669.8 5705.2 5705.9 5717.4 6221.0 6321.4 6323.7 6325.5 6328.3 5712.5 5840.0 5881.4 5930.1 5993.6 5642.3 5648.3 5720.2 5726.9 5857.4 5334.7 5476.0 5477.8 5478.4 5482.7 4108.2 4125.5 4208.2 4228.2 4379.0 4087.0 4125.1 4191.5 4225.1 4327.2 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | [#4] prototype.html Cycles:180 Average:4147.06 Median:4738.74 stddev:1205.68 (25.4%) stddev-sans-first:1174.43 09:44:59 INFO - PROCESS | 10856 | Values: 324.8 329.6 330.2 330.7 332.5 4737.7 4779.9 4819.4 4821.2 4853.9 4770.7 4777.9 4786.2 4791.0 4798.5 3589.6 3707.6 3715.6 3789.7 3839.6 3767.5 3767.9 3801.6 3806.4 3811.9 3762.9 3767.3 3775.5 3778.9 3783.8 3577.4 3650.2 3692.2 3710.7 3772.0 3343.9 3347.4 3350.4 3365.4 3367.4 4644.2 4669.4 4676.8 4687.0 4694.2 4002.7 4302.8 4306.5 4314.0 4330.2 4930.2 5013.3 5043.9 5045.6 5073.9 3501.2 3523.3 3529.8 3535.0 3536.0 5240.6 5270.9 5281.8 5286.2 5299.8 3373.9 3484.7 3542.8 3546.8 3559.1 3581.7 3582.1 3586.6 3599.6 3608.7 4161.9 4173.6 4187.0 4187.8 4226.0 4882.3 5010.7 5047.5 5065.5 5077.8 1227.6 1240.9 1241.1 1242.2 1246.4 806.2 820.9 840.9 843.4 848.6 4291.1 4366.0 4410.9 4448.1 4507.5 5141.3 5182.7 5196.8 5227.3 5244.6 4457.5 4458.3 4464.6 4472.5 4483.6 2612.8 2688.6 2698.0 2722.6 2733.8 4791.2 4806.2 4811.2 4814.8 4825.9 4994.4 5178.7 5187.2 5291.5 5296.7 4719.4 4728.0 4768.1 4786.6 4791.8 4964.5 4995.9 5055.9 5065.8 5074.2 4706.3 4957.8 4962.2 4993.5 4996.3 4720.8 4729.2 4739.8 4741.2 4750.2 3697.5 3698.7 3706.7 3709.1 3783.2 4993.7 5035.8 5064.3 5068.6 5087.2 4766.6 5055.7 5067.8 5086.1 5092.5 5037.5 5044.3 5052.2 5087.7 5111.7 4829.9 4964.8 4970.6 4978.6 5007.3 4836.4 4949.7 5026.3 5055.1 5082.9 4824.7 4929.0 4946.5 4967.9 4989.2 09:44:59 INFO - PROCESS | 10856 | 09:44:59 INFO - PROCESS | 10856 | [#5] yui.html Cycles:180 Average:14104.91 Median:25092.91 stddev:16894.15 (67.3%) stddev-sans-first:16911.27 09:44:59 INFO - PROCESS | 10856 | Values: 637.8 721.6 726.4 727.6 729.5 12253.8 13593.3 13602.7 13609.1 13648.0 12519.9 12617.6 12623.2 12631.3 12636.2 619.1 637.0 752.4 756.9 772.3 773.3 782.1 783.5 783.5 784.0 830.6 832.7 832.8 833.2 834.5 649.3 668.0 795.2 809.4 826.4 606.0 606.2 606.8 607.2 607.2 13175.1 13241.2 13252.4 13259.4 13269.7 11376.5 11831.2 11921.2 11923.4 12578.6 11660.1 11830.7 11835.4 11951.8 12115.6 4035.3 4114.6 4117.5 4157.9 4295.5 64979.4 65397.0 66135.3 66264.7 67068.3 9937.2 9979.7 10064.4 10255.7 10301.0 17765.5 17774.1 17782.4 17782.6 17785.9 6056.7 6421.6 6574.2 6683.9 6946.1 3658.1 3822.2 3825.2 3860.9 3905.9 2592.2 2662.0 2665.5 2666.7 2678.9 483.0 487.3 487.9 489.7 493.4 484.7 514.9 564.7 573.3 603.5 7909.3 8201.2 8281.8 8314.8 8401.7 487.9 514.4 558.3 614.0 622.0 3955.5 4057.4 4078.1 4157.7 4268.5 49833.0 51706.0 52363.7 58988.4 59306.7 50933.7 51144.2 51280.0 51598.7 52230.7 45890.3 46129.2 47205.8 47561.4 48166.3 8525.7 9021.4 9048.1 9129.1 9372.3 33313.3 34376.3 34564.8 34757.7 35102.3 39014.4 39463.0 40921.0 41144.6 42662.1 10837.9 10889.6 11215.6 11306.8 11833.4 12211.7 12503.1 12524.1 12658.6 12977.0 11400.0 11525.0 11749.4 11952.8 12125.5 12170.0 12173.5 12417.4 12664.4 12675.4 10820.7 10858.5 10978.5 11046.4 11082.7 9576.3 9587.1 9638.5 9700.6 9985.5 9382.7 9911.2 10062.2 10064.5 10114.6 09:44:59 INFO - PROCESS | 10856 | -------- Summary: end -------- 09:44:59 INFO - PROCESS | 10856 | 09:45:00 INFO - TEST-INFO | 10856: exit 0 09:45:00 INFO - TEST-OK | dromaeo_css | took 1123510ms 09:45:00 INFO - TEST-START | kraken 09:45:00 INFO - operating with platform_type : linux_ 09:45:00 INFO - Initialising browser for kraken test... 09:45:00 INFO - TEST-INFO | started process 11156 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwA8cU_/profile http://localhost:54967/getInfo.html) 09:45:05 INFO - PROCESS | 11156 | __metrics Screen width/height:1600/1200 09:45:05 INFO - PROCESS | 11156 | colorDepth:24 09:45:05 INFO - PROCESS | 11156 | Browser inner width/height: 1024/697 09:45:05 INFO - PROCESS | 11156 | __metrics 09:45:05 INFO - PROCESS | 11156 | 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:45:05 INFO - PROCESS | 11156 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:45:06 INFO - PROCESS | 11156 | console.error: PushService: 09:45:06 INFO - PROCESS | 11156 | stateChangeProcessEnqueue: Error transitioning state 09:45:06 INFO - PROCESS | 11156 | UnknownError 09:45:07 INFO - TEST-INFO | 11156: exit 0 09:45:07 INFO - Browser initialized. 09:45:07 INFO - Running cycle 1/1 for kraken test... 09:45:07 INFO - TEST-INFO | started process 11238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwA8cU_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:19 INFO - PROCESS | 11238 | RSS: Main: 173383680 09:45:19 INFO - PROCESS | 11238 | 09:45:22 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54967/tests/kraken/driver.html?testName=audio-beat-detection) 09:45:23 INFO - PROCESS | 11238 | RSS: Main: 235040768 09:45:23 INFO - PROCESS | 11238 | 09:45:26 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54967/tests/kraken/driver.html?testName=audio-dft) 09:45:26 INFO - PROCESS | 11238 | RSS: Main: 217767936 09:45:26 INFO - PROCESS | 11238 | 09:45:29 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54967/tests/kraken/driver.html?testName=audio-fft) 09:45:30 INFO - PROCESS | 11238 | RSS: Main: 300761088 09:45:30 INFO - PROCESS | 11238 | 09:45:32 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54967/tests/kraken/driver.html?testName=audio-oscillator) 09:45:33 INFO - PROCESS | 11238 | RSS: Main: 211324928 09:45:33 INFO - PROCESS | 11238 | 09:45:35 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54967/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:45:36 INFO - PROCESS | 11238 | RSS: Main: 214269952 09:45:36 INFO - PROCESS | 11238 | 09:45:39 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54967/tests/kraken/driver.html?testName=imaging-darkroom) 09:45:40 INFO - PROCESS | 11238 | RSS: Main: 253562880 09:45:40 INFO - PROCESS | 11238 | 09:45:43 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54967/tests/kraken/driver.html?testName=imaging-desaturate) 09:45:44 INFO - PROCESS | 11238 | RSS: Main: 248569856 09:45:44 INFO - PROCESS | 11238 | 09:45:47 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54967/tests/kraken/driver.html?testName=json-parse-financial) 09:45:47 INFO - PROCESS | 11238 | RSS: Main: 250388480 09:45:47 INFO - PROCESS | 11238 | 09:45:49 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54967/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:45:50 INFO - PROCESS | 11238 | RSS: Main: 213090304 09:45:50 INFO - PROCESS | 11238 | 09:45:52 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:45:52 INFO - PROCESS | 11238 | RSS: Main: 220307456 09:45:52 INFO - PROCESS | 11238 | 09:45:55 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:45:55 INFO - PROCESS | 11238 | RSS: Main: 229396480 09:45:55 INFO - PROCESS | 11238 | 09:45:58 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:45:59 INFO - PROCESS | 11238 | RSS: Main: 238424064 09:45:59 INFO - PROCESS | 11238 | 09:46:02 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:46:02 INFO - PROCESS | 11238 | RSS: Main: 235270144 09:46:02 INFO - PROCESS | 11238 | 09:46:04 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:54967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54967/tests/kraken/driver.html?testName=ai-astar) 09:46:05 INFO - PROCESS | 11238 | RSS: Main: 288788480 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | __start_tp_report 09:46:05 INFO - PROCESS | 11238 | _x_x_mozilla_page_load 09:46:05 INFO - PROCESS | 11238 | _x_x_mozilla_page_load_details 09:46:05 INFO - PROCESS | 11238 | |i|pagename|runs| 09:46:05 INFO - PROCESS | 11238 | |0;ai-astar;107;118;118;118;118;119;117;118;118;117 09:46:05 INFO - PROCESS | 11238 | |1;audio-beat-detection;153;129;129;180;131;132;130;128;146;132 09:46:05 INFO - PROCESS | 11238 | |2;audio-dft;162;161;164;162;162;163;162;163;163;163 09:46:05 INFO - PROCESS | 11238 | |3;audio-fft;75;76;76;117;76;78;78;76;91;77 09:46:05 INFO - PROCESS | 11238 | |4;audio-oscillator;84;84;116;85;84;85;100;84;85;111 09:46:05 INFO - PROCESS | 11238 | |5;imaging-gaussian-blur;127;127;127;127;127;127;128;126;127;127 09:46:05 INFO - PROCESS | 11238 | |6;imaging-darkroom;147;147;147;146;147;146;147;147;146;146 09:46:05 INFO - PROCESS | 11238 | |7;imaging-desaturate;96;96;96;96;96;96;96;96;96;96 09:46:05 INFO - PROCESS | 11238 | |8;json-parse-financial;65;64;65;65;65;65;65;65;65;65 09:46:05 INFO - PROCESS | 11238 | |9;json-stringify-tinderbox;68;68;67;67;69;68;68;68;68;67 09:46:05 INFO - PROCESS | 11238 | |10;stanford-crypto-aes;88;86;85;85;85;95;91;85;85;85 09:46:05 INFO - PROCESS | 11238 | |11;stanford-crypto-ccm;140;142;146;148;142;141;139;143;194;140 09:46:05 INFO - PROCESS | 11238 | |12;stanford-crypto-pbkdf2;181;176;181;176;179;179;179;183;178;183 09:46:05 INFO - PROCESS | 11238 | |13;stanford-crypto-sha256-iterative;71;73;68;68;69;69;69;68;74;68 09:46:05 INFO - PROCESS | 11238 | __end_tp_report 09:46:05 INFO - PROCESS | 11238 | __start_cc_report 09:46:05 INFO - PROCESS | 11238 | _x_x_mozilla_cycle_collect,1963 09:46:05 INFO - PROCESS | 11238 | __end_cc_report 09:46:05 INFO - PROCESS | 11238 | __startTimestamp1481823965045__endTimestamp 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | ------- Summary: start ------- 09:46:05 INFO - PROCESS | 11238 | Number of tests: 14 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#0] ai-astar Cycles:10 Average:116.80 Median:118.00 stddev:3.49 (3.0%) stddev-sans-first:0.60 09:46:05 INFO - PROCESS | 11238 | Values: 107.0 118.0 118.0 118.0 118.0 119.0 117.0 118.0 118.0 117.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:132.00 stddev:16.63 (12.6%) stddev-sans-first:16.85 09:46:05 INFO - PROCESS | 11238 | Values: 153.0 129.0 129.0 180.0 131.0 132.0 130.0 128.0 146.0 132.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 09:46:05 INFO - PROCESS | 11238 | Values: 162.0 161.0 164.0 162.0 162.0 163.0 162.0 163.0 163.0 163.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.50 stddev:13.15 (17.2%) stddev-sans-first:13.70 09:46:05 INFO - PROCESS | 11238 | Values: 75.0 76.0 76.0 117.0 76.0 78.0 78.0 76.0 91.0 77.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:84.00 stddev:12.49 (14.9%) stddev-sans-first:12.92 09:46:05 INFO - PROCESS | 11238 | Values: 84.0 84.0 116.0 85.0 84.0 85.0 100.0 84.0 85.0 111.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#5] imaging-gaussian-blur Cycles:10 Average:127.00 Median:127.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:46:05 INFO - PROCESS | 11238 | Values: 127.0 127.0 127.0 127.0 127.0 127.0 128.0 126.0 127.0 127.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:46:05 INFO - PROCESS | 11238 | Values: 147.0 147.0 147.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:46:05 INFO - PROCESS | 11238 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 09:46:05 INFO - PROCESS | 11238 | Values: 65.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 09:46:05 INFO - PROCESS | 11238 | Values: 68.0 68.0 67.0 67.0 69.0 68.0 68.0 68.0 68.0 67.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:85.50 stddev:3.43 (4.0%) stddev-sans-first:3.62 09:46:05 INFO - PROCESS | 11238 | Values: 88.0 86.0 85.0 85.0 85.0 95.0 91.0 85.0 85.0 85.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:142.50 stddev:16.57 (11.6%) stddev-sans-first:17.36 09:46:05 INFO - PROCESS | 11238 | Values: 140.0 142.0 146.0 148.0 142.0 141.0 139.0 143.0 194.0 140.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:180.00 stddev:2.51 (1.4%) stddev-sans-first:2.60 09:46:05 INFO - PROCESS | 11238 | Values: 181.0 176.0 181.0 176.0 179.0 179.0 179.0 183.0 178.0 183.0 09:46:05 INFO - PROCESS | 11238 | 09:46:05 INFO - PROCESS | 11238 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.00 stddev:2.21 (3.2%) stddev-sans-first:2.30 09:46:05 INFO - PROCESS | 11238 | Values: 71.0 73.0 68.0 68.0 69.0 69.0 69.0 68.0 74.0 68.0 09:46:05 INFO - PROCESS | 11238 | -------- Summary: end -------- 09:46:05 INFO - PROCESS | 11238 | 09:46:06 INFO - TEST-INFO | 11238: exit 0 09:46:06 INFO - TEST-OK | kraken | took 65767ms 09:46:06 INFO - SUITE-END | took 1189s 09:46:06 INFO - Completed test suite (00:19:49) 09:46:06 INFO - javascript benchmark 09:46:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2580.380708629929, 2608.0, 2624.199619116169, 2636.644053052837, 2654.9469010619787, 4547.454305483347, 4593.655475839309, 4705.200115980283, 4708.152532544141, 4708.929366059508, 4627.537246275371, 4628.930566041512, 4637.9072418551605, 4675.181843177447, 4708.98756767295, 4234.470691163605, 4281.571842815724, 4294.527601963781, 4320.675949303799, 4394.912101757963, 4260.552641972595, 4291.334843099325, 4301.698881078326, 4317.43873296471, 4354.934675979863, 4122.051928056534, 4268.381084742695, 4281.871543853689, 4286.0999190170105, 4362.1275744851, 4131.5042194936495, 4196.7272127311935, 4197.790110494463, 4211.515675697304, 4212.347086201644, 4149.564295748964, 4184.832606695729, 4219.915601687949, 4259.914801703949, 4273.636740877029, 4566.246569316058, 4579.084183163347, 4592.081583683277, 4594.74728889911, 4624.1445332613575, 4897.926531102036, 4901.1177987962155, 4902.509749025105, 4902.8774280643265, 4956.107900577895, 4784.832530861403, 4823.493533179036, 4828.0, 4879.121758083544, 4937.555619994182, 4583.9541604583865, 4594.517575654575, 4594.954050459452, 4632.0, 4641.74470404131, 5014.749262536859, 5021.196608542616, 5022.748862556858, 5026.547610715053, 5070.340855688811, 4623.676342656049, 4659.254519276899, 4812.855614331575, 4814.470408255089, 4867.415910090812, 4814.0, 4888.804447822118, 4912.484189160158, 4924.433690125681, 4981.32752078472, 4897.363342765418, 4906.705597664151, 4906.975465122651, 4911.754412279443, 4923.876903077452, 4878.585320247747, 4893.388326459192, 4902.803887844518, 4904.229749485825, 4939.357883475197, 4202.411662367271, 4229.91540169201, 4231.682623803227, 4236.406903033578, 4265.680073994463, 3720.795356255378, 3733.6079711629886, 3758.567764707094, 3771.7548359356556, 3789.5263092113487, 4232.492100947844, 4233.661307095425, 4255.297875850512, 4278.101598664253, 4303.74177549348, 4835.95164048362, 4880.1215781159735, 4883.731394773251, 4917.385326834145, 4932.161532539477, 4255.744655320691, 4291.399204111427, 4308.353746937922, 4320.503142138631, 4334.609885110292, 4467.687261891637, 4473.217186992263, 4483.865484035484, 4488.506264310923, 4552.521985191574, 4477.417935668343, 4609.262517997105, 4616.930746038879, 4620.029793743352, 4650.279206722963, 4772.3557319761385, 4857.198562237332, 4891.413030436371, 4895.363602731622, 4898.53463920927, 4568.543145685405, 4579.702319349231, 4587.77061146948, 4610.031893302444, 4615.399998000373, 4834.661573689808, 4841.201201801663, 4841.491643377394, 4844.0554091951735, 4891.5353041461, 4742.952570474251, 4760.166970780169, 4773.164696178085, 4822.758862056953, 4908.631852610997, 4647.86056418308, 4673.0, 4677.181493238738, 4728.1252968201, 4731.597814185695, 4636.374089498009, 4678.555537223959, 4682.625389968863, 4722.5513576210205, 4727.905441891074, 4609.21643320643, 4614.169449498988, 4633.2818413146015, 4645.210314246654, 4693.953060469352, 4768.356271903387, 4784.258439941815, 4800.4479484859685, 4836.806527738851, 4841.830535931225, 4619.86140415788, 4654.4414670239785, 4691.695039822401, 4746.952530474651, 4759.048190362011], "value": 4513.767714275797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.4497942222399, 536.9006733754255, 553.8061678412556, 555.6360583817599, 570.3554982869357, 27865.164045078654, 40451.79774101133, 42899.14201715968, 43059.56940430595, 43073.35389969153, 40040.799796001054, 40560.18879622406, 40608.0, 40630.79684601573, 40642.59357406425, 7629.0, 7782.299593036626, 7812.3359514441345, 7813.179616140309, 7814.648340824677, 7467.402607791364, 7654.158042615308, 9766.169875560587, 10002.299839011272, 10026.9498652507, 10054.195664346835, 10099.29304948654, 10120.342177758459, 10126.291159618793, 10127.544260508257, 10031.0, 10101.292909496338, 10101.393916364968, 10109.292349535463, 10118.13995810357, 9934.304598678023, 9991.200703943667, 9997.650082247086, 9999.700008999669, 10003.499825008721, 36182.0, 36570.81714591436, 36607.81696091529, 36651.4502282466, 36665.633343666486, 2965.5996440480476, 3071.8156910585435, 3164.7626428017993, 3557.1462848916076, 3616.385214513539, 28289.43421131566, 28320.291992700142, 28720.0, 29193.0, 29414.70585294141, 3098.659147493774, 3148.8740450381956, 3190.6649801770945, 3233.4826427771677, 3265.591801024872, 235356.0, 235458.0, 235500.0, 236187.99999999828, 236231.0, 52812.0, 53272.73363633157, 53319.466805332224, 53330.73334633302, 53380.73309633427, 55452.44547554554, 55558.444415556136, 55918.440815592134, 55937.16094258589, 56046.0, 13736.0, 14186.432542698383, 14262.786058209134, 14276.0727301815, 14280.42878284857, 9318.0, 10692.465376731132, 10846.18653600983, 10863.0, 10873.184511161695, 14570.708585828224, 16478.176091195393, 16826.326946922232, 17099.401520946707, 17663.20515576802, 5984.31180414258, 6000.849978750566, 6106.145139680449, 6155.630662160284, 6196.690165491707, 773.2036002917005, 876.4565969099199, 896.6099746610246, 1005.2008653120787, 1018.4754851251612, 26418.0, 27595.826479955063, 28494.430111397654, 28588.857055714587, 28633.713662863105, 949.5632009275672, 968.5738275158908, 968.9612415503301, 978.8042391521724, 1017.4912543728136, 13562.59312220635, 13925.791113133311, 14350.354234059492, 14522.128672279698, 15168.393264269527, 349131.0, 349682.0, 352370.0, 353202.0, 353930.0, 42974.28102875913, 43044.56955430468, 43116.78441607772, 43191.35212971808, 43342.56657433449, 1052.079430498314, 1088.804015277226, 1098.9945050274698, 1107.335598640825, 1165.7785020846013, 23052.193173239528, 26089.347766305236, 26301.0, 26365.4726905457, 26606.201813945612, 36812.63187368092, 37140.628593713715, 37169.25661486701, 37246.0, 37250.254994899406, 280321.0, 280336.0, 281269.0, 281485.0, 284562.0, 22158.335249942527, 23130.884345578164, 23174.0, 24252.272431827074, 24411.633825492278, 36351.091222719646, 36423.63576364202, 36454.8177259112, 36609.0, 37541.43687844739, 31470.842645786626, 31543.0, 31576.684233158292, 31578.473722101717, 32605.0, 30862.69137308688, 31480.37039259248, 31657.525137122502, 31711.682883170874, 31829.20426989251, 31280.0, 32006.6799332013, 32120.35759284848, 32245.67754322427, 32446.675533244364, 26663.466730665667, 28469.572956406086, 28703.42593148167, 28901.42197156087, 29101.12696619105, 22696.43258918474, 22884.31347059591, 22994.310170694906, 23218.722970236122, 23230.53538929178], "value": 17019.823415498955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.50240146983924, 343.4624812168957, 361.1675088920039, 361.56973201889747, 381.7856432690395, 12413.379331033446, 14043.929780351096, 14264.0, 16357.509274721777, 16385.180740962933, 15067.849321506808, 15237.390504379839, 15248.313825877834, 15319.846801531981, 15335.84664153358, 4291.249031419505, 4501.414816073922, 4810.302506136608, 5103.132467480537, 5105.770240339175, 4969.8012079516775, 5069.188929771238, 5100.489951004888, 5107.284980102789, 5113.2330150477355, 4849.321095046696, 5083.491650834905, 5085.3389059422225, 5087.084324821531, 5088.54203121719, 4992.40091189056, 5001.1248031594505, 5003.424606170301, 5021.774020169101, 5044.873878153076, 4314.676399270036, 4464.732116073046, 4471.396361491222, 4473.865784026484, 4477.932831007537, 18937.62124757497, 19291.22835086595, 19386.127624256944, 19442.22231110754, 19465.318713844947, 1772.1733834533898, 1986.145957238387, 2019.4446527204989, 2058.763242227148, 2502.036715864387, 12157.939210303892, 12665.0, 12818.87181128186, 13219.405126769223, 13312.667183320398, 1680.2522877319598, 1754.429810311654, 1777.6444711057711, 1780.3234770787149, 1780.5370603643016, 65359.67320163369, 69482.65258673725, 69886.0, 70001.0, 70382.0, 9382.62469501226, 9705.029497050238, 9718.659846905324, 9793.314467987173, 9807.411555306562, 12973.481060757653, 13127.803082953764, 13132.212067275803, 13134.540291089766, 13227.206367617973, 6755.695993680296, 6804.6257455840405, 6868.278830722802, 6960.051185668021, 6963.1644202696, 6338.207724034496, 6397.808065758035, 6478.125453063871, 6514.674266286666, 6576.86846263072, 3744.719146064056, 3895.7467764595212, 4005.599440055971, 4196.328587425997, 4244.151169766059, 1766.4523997560798, 1777.111444277861, 1801.4055361730598, 1819.3632228720107, 1830.6704793137362, 766.6051983228642, 798.2496453333847, 811.2941740685524, 890.2062344375958, 906.7959709065408, 15497.30262138184, 16043.197840107949, 16080.115593642462, 16425.83574164243, 16759.0, 855.9914400855912, 870.6256309786726, 875.7722992021993, 882.5322579033123, 927.3925578745932, 8655.6970506032, 9216.262698984065, 9370.62517499306, 9405.0, 9557.187639050619, 319260.6332715033, 322218.0, 322885.0, 324266.0, 329783.0, 72235.0, 72421.63789181126, 73744.63127684327, 75834.3457978968, 76813.61593191998, 864.5331520978601, 898.9865152022725, 911.0752586125088, 914.0311270053631, 925.9305552083621, 14313.57059288223, 14363.928180359031, 14451.638709032359, 14568.34442450071, 14926.477573284883, 34075.14812129716, 34318.14204644904, 35518.289634207686, 35636.287274254886, 37134.8143259282, 46146.38536146359, 48245.0, 50290.2456463146, 52233.7388313056, 54068.0, 6572.868542629216, 7027.121609798775, 7337.926620733724, 7432.442566807403, 7434.553926764412, 8552.957235213784, 10087.0, 11125.721856953318, 11568.768624627628, 11705.180637355645, 9333.346665733334, 9981.800363992825, 10023.649172278936, 10094.545745441326, 10102.141317988053, 9663.13031827139, 9866.0, 10184.592616295266, 10200.897991019994, 10243.282970192313, 10342.482875856327, 10389.220808439548, 10560.841587376042, 10598.841017384591, 10841.265397536356, 6178.474829639531, 6279.340669229665, 6408.807735767934, 6540.215174179129, 7045.471589630695, 7158.677859496439, 9581.329306948446, 10063.194944404577, 10329.0, 10656.84014739795], "value": 7854.297232697541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.1287643716926, 1334.2528184216835, 1426.9357878895448, 1623.7807895934045, 1669.8246684098162, 5918.585699001072, 6095.756169753205, 6325.620462772231, 6730.091437655915, 6731.3268673132725, 6222.657753823538, 6459.580127291722, 6474.158359413282, 6493.285738568754, 6650.102236198112, 1987.0561483295428, 2044.631966246075, 2054.2296638760463, 2055.085486958304, 2055.5580550181708, 1779.5729025034027, 1835.0182652280967, 2006.829419499337, 2099.055425058722, 2108.57828434313, 2011.7083022961663, 2034.725312082872, 2035.8269547088441, 2039.6022775558772, 2041.050911326233, 1969.9212031518723, 1971.4775584469983, 1973.881567105971, 1974.3385965701507, 2014.6776515757556, 2037.1749441476227, 2039.367795983235, 2077.6571865642304, 2078.9480262993393, 2080.06397121296, 6806.693698783567, 6858.177018757731, 6858.794236172923, 6861.039454476378, 6865.862682746367, 4039.2931237033695, 4164.5210800757695, 4171.416001759756, 4177.665786737054, 4187.330027195664, 2589.1196993022463, 2741.8354898705943, 2819.478396496634, 2821.50623640862, 2828.0950095969297, 774.7559518751632, 815.975520734379, 845.4969293270494, 848.9023762267357, 849.7365816596813, 8633.0, 8865.33509986747, 8873.0, 8880.378373513857, 8881.73354799357, 3585.103724068983, 3764.265968136187, 3794.051487128218, 3848.6536211741077, 3877.1664092219744, 7401.555906645618, 7465.58939258346, 7470.962645186739, 7477.065366829146, 7484.0270764800225, 4136.276151673445, 4215.451991241181, 4219.957800421957, 4223.450951376301, 4226.619604235633, 3798.7254407809287, 3804.8858534243464, 3858.054776579756, 3859.1895701902913, 3871.090293780959, 3136.8274744888795, 3149.401613693391, 3220.404225218342, 3230.03099070278, 3231.4021905947475, 1027.4913917610681, 1067.1889364083197, 1070.4433694478828, 1071.185898716965, 1074.6883403812963, 789.8341348316918, 792.3700657976922, 798.4171554765054, 824.674253669797, 826.9586520673943, 4543.477500087466, 4578.862634120982, 4607.470140933836, 4763.309320148628, 4847.0, 371.21858487883156, 373.534948988511, 378.49092969955365, 378.78977167671655, 381.0682880357499, 3310.4344133296886, 3337.3992681317595, 3342.9164270892934, 3344.6989770920245, 3373.1735724747596, 7233.095863017123, 7514.173440921494, 7725.690972361042, 7725.806854828562, 7766.37868970481, 7636.770896873103, 7694.692212311556, 7696.0, 7700.268474494914, 7700.614969251515, 6067.787627433018, 6087.939120608826, 6170.598911070766, 6192.721327540272, 6203.96898015516, 4477.216487114677, 4491.191585514508, 4541.977290113528, 4630.398048253705, 4714.363560919232, 6999.370056694922, 7129.750458733919, 7154.570725756471, 7154.606496642735, 7214.74748383804, 7247.601381923834, 7331.156916954618, 7342.70629174827, 7353.632318383952, 7361.1166659999, 5550.4449555044175, 5669.801556945487, 5705.2297939779255, 5705.857353566194, 5717.42825717425, 6221.0, 6321.399467050623, 6323.652199129092, 6325.525585581192, 6328.335524770017, 5712.514436272963, 5840.0, 5881.382454842351, 5930.140129681258, 5993.550483713651, 5642.294713160855, 5648.293963254593, 5720.227769251264, 5726.914096288642, 5857.414258574108, 5334.706591137525, 5475.972620136874, 5477.835664930059, 5478.397376288684, 5482.671039737628, 4108.198901214354, 4125.50493940729, 4208.2004419161485, 4228.1966426378885, 4379.0366119453665, 4086.9795651022746, 4125.071858831739, 4191.497020357577, 4225.112726327506, 4327.199468098412], "value": 3575.9767766631976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.7840186276126, 329.6077667575585, 330.20585491892007, 330.66602731241454, 332.497928128526, 4737.692050016746, 4779.904401911964, 4819.397575303087, 4821.240247309733, 4853.902921941563, 4770.7376094314795, 4777.928331075037, 4786.234202527597, 4790.976045119778, 4798.47216806151, 3589.6051434342203, 3707.5725845549523, 3715.5541335039698, 3789.696824254067, 3839.59684233155, 3767.5478942526797, 3767.905802354949, 3801.581825999138, 3806.448065030569, 3811.866584669537, 3762.9059273518105, 3767.3407153747985, 3775.5086740737393, 3778.905527361824, 3783.772973621578, 3577.4097273949897, 3650.1787097903025, 3692.1692619160744, 3710.666040056381, 3772.019274988509, 3343.916402089943, 3347.4142025145748, 3350.3634309481363, 3365.4110530657035, 3367.3938691035605, 4644.210484217691, 4669.439667239919, 4676.812927482896, 4687.0, 4694.201985662446, 4002.7398219115958, 4302.827886884521, 4306.5262821089655, 4314.0, 4330.17726631939, 4930.161872481687, 5013.273075404082, 5043.949560504385, 5045.571126454256, 5073.949260507385, 3501.1772233525103, 3523.277728065766, 3529.8058606776617, 3534.9748572913822, 3535.982320088409, 5240.558846317255, 5270.894582108336, 5281.762320695578, 5286.207068939651, 5299.788008479656, 3373.9325213495595, 3484.738644601665, 3542.8405721742583, 3546.8226588670464, 3559.110222444389, 3581.7492775505466, 3582.1223800169128, 3586.6413358664445, 3599.5500562429697, 3608.6752192302292, 4161.9375709364385, 4173.582641735802, 4187.0, 4187.832486700498, 4225.9577404226175, 4882.292067650171, 5010.674306170087, 5047.4952504749235, 5065.493450654979, 5077.791160845044, 1227.6010296653624, 1240.8584102625607, 1241.1374095004066, 1242.1801610936739, 1246.3548116486331, 806.1696452653777, 820.885076089348, 840.932725381968, 843.409613270713, 848.6393282854762, 4291.0559676871035, 4365.956340436618, 4410.933835992462, 4448.088141930896, 4507.549245075466, 5141.305923700327, 5182.663126896739, 5196.76614552346, 5227.320448341691, 5244.632875698664, 4457.500861823518, 4458.331250312414, 4464.642828573771, 4472.463304403493, 4483.618892394118, 2612.7648511633665, 2688.5967104934416, 2698.0, 2722.632444619951, 2733.753962143377, 4791.209450440708, 4806.182948898696, 4811.158047341702, 4814.844437335014, 4825.855224343275, 4994.375703037121, 5178.715170665574, 5187.213334266007, 5291.470852914755, 5296.655717378435, 4719.433667959866, 4728.0, 4768.117898188846, 4786.64100192487, 4791.760411979457, 4964.503549645151, 4995.8751031223055, 5055.9494405055475, 5065.797368105234, 5074.238864170404, 4706.317583950239, 4957.801687932442, 4962.181240095344, 4993.500649934977, 4996.3005179274205, 4720.763961801965, 4729.179265982691, 4739.7867095981455, 4741.170295198395, 4750.216214324598, 3697.5378077740284, 3698.704103671646, 3706.7405281631104, 3709.10981364476, 3783.2244389900557, 4993.650444468853, 5035.84892453227, 5064.265681476091, 5068.569171620454, 5087.23691446286, 4766.571008609242, 5055.7472126392795, 5067.847964561069, 5086.109126518142, 5092.465291144376, 5037.496250374933, 5044.344235249394, 5052.236084117209, 5087.669301495391, 5111.674188244414, 4829.855104346875, 4964.751762411793, 4970.6023518117745, 4978.60171186297, 5007.273945277987, 4836.443808961873, 4949.698390248552, 5026.346574945233, 5055.115354812967, 5082.949170508247, 4824.662273640812, 4928.975355123202, 4946.480619534897, 4967.900641987212, 4989.176785830297], "value": 3770.728352396208, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.7927173668559, 721.577876941989, 726.4370113162302, 727.5961841178146, 729.51122747759, 12253.816192757105, 13593.252371119597, 13602.727945441085, 13609.115407498506, 13648.044636875422, 12519.937400313009, 12617.55838545651, 12623.179493332927, 12631.305278209718, 12636.178648387848, 619.0590302739832, 636.9968150159243, 752.4018405367718, 756.9356604688594, 772.3435080181856, 773.2615352338503, 782.104490358542, 783.4554984285924, 783.4750717019582, 783.9529628222297, 830.5764060329245, 832.7293629570383, 832.7918020494876, 833.2375876073386, 834.5243211369522, 649.2792999770274, 668.004673037176, 795.2127393880018, 809.4091313341294, 826.3760860550276, 605.9787907423218, 606.2421972534332, 606.769427617507, 607.2409488139825, 607.2409488139825, 13175.14361566495, 13241.205527668371, 13252.403641836141, 13259.403326850315, 13269.734605307936, 11376.544938202462, 11831.171817972745, 11921.165518413714, 11923.403829808562, 12578.55975040869, 11660.12549058824, 11830.704232394259, 11835.408229588485, 11951.760964780742, 12115.575954841539, 4035.3341698619693, 4114.5679703630985, 4117.464729585135, 4157.937630935538, 4295.506016808075, 64979.350206498275, 65397.0, 66135.3386466134, 66264.66867665679, 67068.3293167067, 9937.205023598239, 9979.650712225037, 10064.396136231702, 10255.692329230134, 10301.0, 17765.467035988942, 17774.111294435228, 17782.37761678335, 17782.644347113244, 17785.911070444567, 6056.666883321372, 6421.550491465552, 6574.211094668671, 6683.899741503881, 6946.0622815920215, 3658.1037645776955, 3822.1973385589336, 3825.2349530094093, 3860.8841734748003, 3905.941410878839, 2592.157548796649, 2662.0150544298344, 2665.4802313549058, 2666.719994400599, 2678.8660566971575, 482.95411935866036, 487.28612582566, 487.8780304923769, 489.7404375680895, 493.44980346912934, 484.73000738065787, 514.948505149482, 564.6753116957766, 573.3091624592356, 603.5322624965687, 7909.344818454832, 8201.179882011871, 8281.751547453587, 8314.750557483285, 8401.663933442716, 487.90729761345233, 514.4495389932811, 558.2714557502417, 613.9662318572522, 621.9533534984894, 3955.545112312121, 4057.3711074783455, 4078.1435898461655, 4157.729747566398, 4268.530461649216, 49833.0, 51706.0, 52363.738181309614, 58988.41011589829, 59306.70346648325, 50933.74533127311, 51144.23283650748, 51280.0, 51598.74200629048, 52230.738846305525, 45890.311645325346, 46129.235445265294, 47205.76397118061, 47561.438940990956, 48166.27750583744, 8525.701600444203, 9021.413608115452, 9048.140426659456, 9129.132732390412, 9372.297077719335, 33313.333733325686, 34376.312473750884, 34564.82717586396, 34757.65242347544, 35102.297954041285, 39014.4147837777, 39463.0, 40921.0, 41144.588554114074, 42662.080206390165, 10837.945810270898, 10889.618863339745, 11215.60745373908, 11306.773864522827, 11833.408329583313, 12211.69470763238, 12503.124781265224, 12524.123311368481, 12658.620241392773, 12977.026722995624, 11400.0, 11525.0, 11749.361555961332, 11952.76094478088, 12125.454354554242, 12170.0, 12173.452194651085, 12417.441215145522, 12664.366781661065, 12675.366231688195, 10820.729481762704, 10858.511366988347, 10978.505967231653, 11046.447677615926, 11082.667519974413, 9576.329656923948, 9587.089226523469, 9638.518074096408, 9700.57974761601, 9985.45080020606, 9382.718518444457, 9911.182280218483, 10062.195024398177, 10064.547095380873, 10114.59541618327], "value": 6168.40670113406, "unit": "score"}], "name": "dromaeo_css", "value": 6073.535687906938, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 118.0, 118.0, 119.0, 117.0, 118.0, 118.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 129.0, 129.0, 180.0, 131.0, 132.0, 130.0, 128.0, 146.0, 132.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 164.0, 162.0, 162.0, 163.0, 162.0, 163.0, 163.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 117.0, 76.0, 78.0, 78.0, 76.0, 91.0, 77.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 85.0, 84.0, 85.0, 100.0, 84.0, 85.0, 111.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 128.0, 126.0, 127.0, 127.0], "value": 127.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 69.0, 68.0, 68.0, 68.0, 68.0, 67.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 85.0, 85.0, 95.0, 91.0, 85.0, 85.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 146.0, 148.0, 142.0, 141.0, 139.0, 143.0, 194.0, 140.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 176.0, 181.0, 176.0, 179.0, 179.0, 179.0, 183.0, 178.0, 183.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 68.0, 68.0, 69.0, 69.0, 69.0, 68.0, 74.0, 68.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1578.1000000000001, "alertThreshold": 2.0}]} 09:46:06 INFO - Return code: 0 09:46:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:46:06 INFO - # TBPL SUCCESS # 09:46:06 INFO - Running post-action listener: _resource_record_post_action 09:46:06 INFO - [mozharness: 2016-12-15 17:46:06.816434Z] Finished run-tests step (success) 09:46:06 INFO - Running post-run listener: _resource_record_post_run 09:46:06 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396472320; Read time: 16; Write time: 311888 09:46:06 INFO - TinderboxPrint: CPU usage
13.2% 09:46:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:46:06 INFO - TinderboxPrint: I/O write bytes / time
396,472,320 / 311,888 09:46:06 INFO - TinderboxPrint: CPU idle
8,354.7 (86.8%) 09:46:06 INFO - TinderboxPrint: CPU user
1,216.0 (12.6%) 09:46:06 INFO - TinderboxPrint: Swap in / out
0 / 0 09:46:06 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107032576; Read time: 0; Write time: 89424 09:46:06 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 289439744; Read time: 0; Write time: 222464 09:46:07 INFO - Running post-run listener: _upload_blobber_files 09:46:07 INFO - Blob upload gear active. 09:46:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:46:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:46:07 INFO - (blobuploader) - INFO - Open directory for files ... 09:46:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:46:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:46:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:11 INFO - (blobuploader) - INFO - Done attempting. 09:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:12 INFO - (blobuploader) - INFO - Done attempting. 09:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:13 INFO - (blobuploader) - INFO - Done attempting. 09:46:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:46:13 INFO - Return code: 0 09:46:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:46:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:46:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e0447a45da80fc221984b72a96e1b3f8ea45512c6cd0485b6fa2ec99df09928ab2ae5d26ce57970de36cc710fb176563e0d37908e12332ac1ed47f3030cef06a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/efb880b0fc6218037ccb861c5d1fb4819c0545c5a0e2aa55e8b5d091d15d7f4e279fb7691b2d0f53d034e9ba357384ea8a34ce6a7311b5fd9f286dddf403543f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/154aaaace9f339f2324535d3ac60dda2ce356d969c51500a8c8713e2aff38b564b03b8bc6267d1188fe15e96a7b30063ce6cc0ad0da9851cc18421a52445cea5"} 09:46:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:46:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:46:13 INFO - Contents: 09:46:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e0447a45da80fc221984b72a96e1b3f8ea45512c6cd0485b6fa2ec99df09928ab2ae5d26ce57970de36cc710fb176563e0d37908e12332ac1ed47f3030cef06a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/efb880b0fc6218037ccb861c5d1fb4819c0545c5a0e2aa55e8b5d091d15d7f4e279fb7691b2d0f53d034e9ba357384ea8a34ce6a7311b5fd9f286dddf403543f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/154aaaace9f339f2324535d3ac60dda2ce356d969c51500a8c8713e2aff38b564b03b8bc6267d1188fe15e96a7b30063ce6cc0ad0da9851cc18421a52445cea5"} 09:46:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:46:13 INFO - Copying logs to upload dir... 09:46:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:46:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.328324 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-15 09:46:12.386027) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 09:46:12.389463) ========= 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=d2a8c569965ab8367867b893000001a1-1481822721.17288-795910151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e0447a45da80fc221984b72a96e1b3f8ea45512c6cd0485b6fa2ec99df09928ab2ae5d26ce57970de36cc710fb176563e0d37908e12332ac1ed47f3030cef06a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/efb880b0fc6218037ccb861c5d1fb4819c0545c5a0e2aa55e8b5d091d15d7f4e279fb7691b2d0f53d034e9ba357384ea8a34ce6a7311b5fd9f286dddf403543f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/154aaaace9f339f2324535d3ac60dda2ce356d969c51500a8c8713e2aff38b564b03b8bc6267d1188fe15e96a7b30063ce6cc0ad0da9851cc18421a52445cea5"} build_url:https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011984 build_url: 'https://queue.taskcluster.net/v1/task/bS1B7FLdRFGG9n_-IXSsTA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e0447a45da80fc221984b72a96e1b3f8ea45512c6cd0485b6fa2ec99df09928ab2ae5d26ce57970de36cc710fb176563e0d37908e12332ac1ed47f3030cef06a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/efb880b0fc6218037ccb861c5d1fb4819c0545c5a0e2aa55e8b5d091d15d7f4e279fb7691b2d0f53d034e9ba357384ea8a34ce6a7311b5fd9f286dddf403543f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/154aaaace9f339f2324535d3ac60dda2ce356d969c51500a8c8713e2aff38b564b03b8bc6267d1188fe15e96a7b30063ce6cc0ad0da9851cc18421a52445cea5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 09:46:12.415613) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:46:12.415969) ========= 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=d2a8c569965ab8367867b893000001a1-1481822721.17288-795910151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004561 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:46:12.500225) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 09:46:12.500576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 09:46:12.500910) ========= ========= Total master_lag: 0.17 =========