builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-082 starttime: 1486643314.01 results: success (0) revision: 75bda9957ea872ab71fbfa63c8f4d57e2458afb6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.006057) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.006440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.006732) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.031396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.031733) ========= 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=51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 04:28:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 33.8M=0s 2017-02-09 04:28:34 (33.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.154527) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.154859) ========= 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=51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017933 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:34.205433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 04:28:34.205758) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75bda9957ea872ab71fbfa63c8f4d57e2458afb6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75bda9957ea872ab71fbfa63c8f4d57e2458afb6 --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=51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 04:28:34,383 truncating revision to first 12 chars 2017-02-09 04:28:34,383 Setting DEBUG logging. 2017-02-09 04:28:34,383 attempt 1/10 2017-02-09 04:28:34,383 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/75bda9957ea8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 04:28:35,242 unpacking tar archive at: mozilla-aurora-75bda9957ea8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.202106 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 04:28:35.421572) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:35.421899) ========= script_repo_revision: 75bda9957ea872ab71fbfa63c8f4d57e2458afb6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:35.422283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:35.422556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:35.430827) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 04:28:35.431104) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:28:35 INFO - MultiFileLogger online at 20170209 04:28:35 in /builds/slave/test 04:28:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:28:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:35 INFO - {'append_to_log': False, 04:28:35 INFO - 'base_work_dir': '/builds/slave/test', 04:28:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:28:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:35 INFO - 'branch': 'Mozilla-Aurora', 04:28:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:35 INFO - 'config_files': ('talos/linux_config.py',), 04:28:35 INFO - 'default_actions': ('clobber', 04:28:35 INFO - 'read-buildbot-config', 04:28:35 INFO - 'download-and-extract', 04:28:35 INFO - 'populate-webroot', 04:28:35 INFO - 'create-virtualenv', 04:28:35 INFO - 'install', 04:28:35 INFO - 'run-tests'), 04:28:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:35 INFO - 'download_minidump_stackwalk': True, 04:28:35 INFO - 'download_symbols': 'ondemand', 04:28:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:35 INFO - 'installer_path': 'installer.exe', 04:28:35 INFO - 'log_level': 'info', 04:28:35 INFO - 'log_name': 'talos', 04:28:35 INFO - 'log_to_console': True, 04:28:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:28:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:28:35 INFO - 'opt_config_files': (), 04:28:35 INFO - 'pip_index': False, 04:28:35 INFO - 'sps_profile': False, 04:28:35 INFO - 'sps_profile_interval': 0, 04:28:35 INFO - 'suite': 'dromaeojs', 04:28:35 INFO - 'system_bits': '32', 04:28:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:35 INFO - 'title': 'talos-linux64-ix-082', 04:28:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:35 INFO - 'use_talos_json': True, 04:28:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:35 INFO - 'work_dir': 'build'} 04:28:35 INFO - [mozharness: 2017-02-09 12:28:35.706303Z] Running clobber step. 04:28:35 INFO - Running pre-action listener: _resource_record_pre_action 04:28:35 INFO - Running main action method: clobber 04:28:35 INFO - rmtree: /builds/slave/test/build 04:28:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:28:36 INFO - Running post-action listener: _resource_record_post_action 04:28:36 INFO - [mozharness: 2017-02-09 12:28:36.683559Z] Finished clobber step (success) 04:28:36 INFO - [mozharness: 2017-02-09 12:28:36.683657Z] Running read-buildbot-config step. 04:28:36 INFO - Running pre-action listener: _resource_record_pre_action 04:28:36 INFO - Running main action method: read_buildbot_config 04:28:36 INFO - Using buildbot properties: 04:28:36 INFO - { 04:28:36 INFO - "project": "", 04:28:36 INFO - "product": "firefox", 04:28:36 INFO - "who": "archaeopteryx@coole-files.de", 04:28:36 INFO - "installer_path": "public/build/target.tar.bz2", 04:28:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:28:36 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 04:28:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:28:36 INFO - "stage_platform": "linux64-pgo", 04:28:36 INFO - "basedir": "/builds/slave/test", 04:28:36 INFO - "buildnumber": 1046, 04:28:36 INFO - "platform": "ubuntu64_hw", 04:28:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:28:36 INFO - "slavebuilddir": "test", 04:28:36 INFO - "taskId": "Ce0Vx9KSTJOHmp7ioUmnEQ", 04:28:36 INFO - "branch": "mozilla-aurora", 04:28:36 INFO - "script_repo_revision": "production", 04:28:36 INFO - "revision": "75bda9957ea872ab71fbfa63c8f4d57e2458afb6", 04:28:36 INFO - "slavename": "talos-linux64-ix-082", 04:28:36 INFO - "repo_path": "releases/mozilla-aurora" 04:28:36 INFO - } 04:28:36 INFO - Finding installer, test and symbols from parent task. 04:28:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ce0Vx9KSTJOHmp7ioUmnEQ'}, attempt #1 04:28:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ce0Vx9KSTJOHmp7ioUmnEQ'}, attempt #1 04:28:37 INFO - Task dependencies: KsvT3t2_R0q6k6uy7P5YCw 04:28:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw'}, attempt #1 04:28:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2 04:28:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/test_packages.json 04:28:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.crashreporter-symbols.zip 04:28:38 INFO - Running post-action listener: _resource_record_post_action 04:28:38 INFO - [mozharness: 2017-02-09 12:28:38.096233Z] Finished read-buildbot-config step (success) 04:28:38 INFO - [mozharness: 2017-02-09 12:28:38.096449Z] Running download-and-extract step. 04:28:38 INFO - Running pre-action listener: _resource_record_pre_action 04:28:38 INFO - Running main action method: download_and_extract 04:28:38 INFO - mkdir: /builds/slave/test/build/tests 04:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:38 INFO - trying https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.test_packages.json 04:28:38 INFO - Downloading https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:28:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:28:38 INFO - Downloaded 896 bytes. 04:28:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:28:38 INFO - Using the following test package requirements: 04:28:38 INFO - {u'common': [u'target.common.tests.zip'], 04:28:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:28:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:28:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:28:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:28:38 INFO - u'mozbase': [u'target.common.tests.zip'], 04:28:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:28:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:28:38 INFO - u'web-platform': [u'target.common.tests.zip', 04:28:38 INFO - u'target.web-platform.tests.zip'], 04:28:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:28:38 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:28:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.common.tests.zip 04:28:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:28:38 INFO - Fetch https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.common.tests.zip into memory 04:28:39 INFO - Expected file size: 21861912 04:28:39 INFO - Obtained file size: 21861912 04:28:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:28:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.common.tests.zip 04:28:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:28:43 INFO - Fetch https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.common.tests.zip into memory 04:28:44 INFO - Expected file size: 21861912 04:28:44 INFO - Obtained file size: 21861912 04:28:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.talos.tests.zip 04:28:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:28:48 INFO - Fetch https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.talos.tests.zip into memory 04:28:49 INFO - Expected file size: 14403485 04:28:49 INFO - Obtained file size: 14403485 04:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:49 INFO - trying https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2 04:28:49 INFO - Downloading https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:28:50 INFO - Downloaded 65967219 bytes. 04:28:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2 04:28:50 INFO - mkdir: /builds/slave/test/properties 04:28:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:28:50 INFO - Writing to file /builds/slave/test/properties/build_url 04:28:50 INFO - Contents: 04:28:50 INFO - build_url:https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2 04:28:50 INFO - Running post-action listener: _resource_record_post_action 04:28:50 INFO - Running post-action listener: set_extra_try_arguments 04:28:50 INFO - [mozharness: 2017-02-09 12:28:50.897378Z] Finished download-and-extract step (success) 04:28:50 INFO - [mozharness: 2017-02-09 12:28:50.897495Z] Running populate-webroot step. 04:28:50 INFO - Running pre-action listener: _resource_record_pre_action 04:28:50 INFO - Running main action method: populate_webroot 04:28:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:28:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:28:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:28:50 INFO - '--tppagecycles', 04:28:50 INFO - '7'], 04:28:50 INFO - 'tests': ['tsvgm']}}, 04:28:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:28:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:28:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:28:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:28:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['damp', 'tps']}, 04:28:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'tests': ['damp', 'tps']}, 04:28:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['dromaeo_dom']}, 04:28:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:28:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['basic_compositor_video']}, 04:28:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:28:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['a11yr', 04:28:50 INFO - 'ts_paint', 04:28:50 INFO - 'tpaint', 04:28:50 INFO - 'sessionrestore', 04:28:50 INFO - 'sessionrestore_no_auto_restore', 04:28:50 INFO - 'tabpaint']}, 04:28:50 INFO - 'other-e10s': {'tests': ['a11yr', 04:28:50 INFO - 'ts_paint', 04:28:50 INFO - 'tpaint', 04:28:50 INFO - 'sessionrestore', 04:28:50 INFO - 'sessionrestore_no_auto_restore', 04:28:50 INFO - 'tabpaint']}, 04:28:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:28:50 INFO - 'ts_paint', 04:28:50 INFO - 'tpaint', 04:28:50 INFO - 'sessionrestore', 04:28:50 INFO - 'sessionrestore_no_auto_restore', 04:28:50 INFO - 'tabpaint']}, 04:28:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:28:50 INFO - 'ts_paint', 04:28:50 INFO - 'tpaint', 04:28:50 INFO - 'sessionrestore', 04:28:50 INFO - 'sessionrestore_no_auto_restore', 04:28:50 INFO - 'tabpaint']}, 04:28:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['a11yr', 04:28:50 INFO - 'ts_paint', 04:28:50 INFO - 'tpaint', 04:28:50 INFO - 'sessionrestore', 04:28:50 INFO - 'sessionrestore_no_auto_restore', 04:28:50 INFO - 'tabpaint']}, 04:28:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['a11yr', 04:28:50 INFO - 'ts_paint', 04:28:50 INFO - 'tpaint', 04:28:50 INFO - 'sessionrestore', 04:28:50 INFO - 'sessionrestore_no_auto_restore', 04:28:50 INFO - 'tabpaint']}, 04:28:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['tsvgx', 04:28:50 INFO - 'tsvgr_opacity', 04:28:50 INFO - 'tart', 04:28:50 INFO - 'tscrollx', 04:28:50 INFO - 'cart', 04:28:50 INFO - 'tsvg_static']}, 04:28:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:28:50 INFO - 'tsvgr_opacity', 04:28:50 INFO - 'tart', 04:28:50 INFO - 'tscrollx', 04:28:50 INFO - 'cart', 04:28:50 INFO - 'tsvg_static']}, 04:28:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'talos_options': ['--disable-e10s'], 04:28:50 INFO - 'tests': ['tp5o']}, 04:28:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:28:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'talos_options': ['--disable-e10s', 04:28:50 INFO - '--xperf_path', 04:28:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:50 INFO - 'tests': ['tp5n']}, 04:28:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:28:50 INFO - 'talos_options': ['--xperf_path', 04:28:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:50 INFO - 'tests': ['tp5n']}}, 04:28:50 INFO - 'talos.zip': {'path': '', 04:28:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:28:50 INFO - Running post-action listener: _resource_record_post_action 04:28:50 INFO - [mozharness: 2017-02-09 12:28:50.912429Z] Finished populate-webroot step (success) 04:28:50 INFO - [mozharness: 2017-02-09 12:28:50.912519Z] Running create-virtualenv step. 04:28:50 INFO - Running pre-action listener: _resource_record_pre_action 04:28:50 INFO - Running main action method: create_virtualenv 04:28:50 INFO - Creating virtualenv /builds/slave/test/build/venv 04:28:50 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 04:28:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:28:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:28:51 INFO - Using real prefix '/usr' 04:28:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:28:51 INFO - Installing distribute.............................................................................................................................................................................................done. 04:28:53 INFO - Installing pip.................done. 04:28:53 INFO - Return code: 0 04:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:28:53 INFO - Reading from file tmpfile_stdout 04:28:53 INFO - Output received: 04:28:53 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) 04:28:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:28:53 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')]} 04:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:53 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')]} 04:28:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:53 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:28:53 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 04:28:53 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 04:28:53 INFO - Using env: {'DISPLAY': ':0', 04:28:53 INFO - 'HOME': '/home/cltbld', 04:28:53 INFO - 'LANG': 'en_US.UTF-8', 04:28:53 INFO - 'LANGUAGE': 'en_US:en', 04:28:53 INFO - 'LOGNAME': 'cltbld', 04:28:53 INFO - 'MAIL': '/var/mail/cltbld', 04:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:53 INFO - 'MOZ_NO_REMOTE': '1', 04:28:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:53 INFO - 'NO_EM_RESTART': '1', 04:28:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:53 INFO - 'PWD': '/builds/slave/test', 04:28:53 INFO - 'SHELL': '/bin/bash', 04:28:53 INFO - 'SHLVL': '1', 04:28:53 INFO - 'TERM': 'linux', 04:28:53 INFO - 'TMOUT': '86400', 04:28:53 INFO - 'USER': 'cltbld', 04:28:53 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:53 INFO - '_': '/tools/buildbot/bin/python'} 04:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:53 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 04:28:53 INFO - Cleaning up... 04:28:53 INFO - Return code: 0 04:28:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:28:53 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')]} 04:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:53 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')]} 04:28:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:53 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:28:53 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 04:28:53 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 04:28:53 INFO - Using env: {'DISPLAY': ':0', 04:28:53 INFO - 'HOME': '/home/cltbld', 04:28:53 INFO - 'LANG': 'en_US.UTF-8', 04:28:53 INFO - 'LANGUAGE': 'en_US:en', 04:28:53 INFO - 'LOGNAME': 'cltbld', 04:28:53 INFO - 'MAIL': '/var/mail/cltbld', 04:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:53 INFO - 'MOZ_NO_REMOTE': '1', 04:28:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:53 INFO - 'NO_EM_RESTART': '1', 04:28:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:53 INFO - 'PWD': '/builds/slave/test', 04:28:53 INFO - 'SHELL': '/bin/bash', 04:28:53 INFO - 'SHLVL': '1', 04:28:53 INFO - 'TERM': 'linux', 04:28:53 INFO - 'TMOUT': '86400', 04:28:53 INFO - 'USER': 'cltbld', 04:28:53 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:53 INFO - '_': '/tools/buildbot/bin/python'} 04:28:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:54 INFO - Downloading/unpacking psutil>=3.1.1 04:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:28:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:56 INFO - Installing collected packages: psutil 04:28:56 INFO - Running setup.py install for psutil 04:28:56 INFO - building 'psutil._psutil_linux' extension 04:28:56 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 04:28:56 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 04:28:56 INFO - building 'psutil._psutil_posix' extension 04:28:56 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 04:28:56 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 04:28:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:56 INFO - Successfully installed psutil 04:28:56 INFO - Cleaning up... 04:28:56 INFO - Return code: 0 04:28:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:56 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:28:56 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 04:28:56 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 04:28:56 INFO - Using env: {'DISPLAY': ':0', 04:28:56 INFO - 'HOME': '/home/cltbld', 04:28:56 INFO - 'LANG': 'en_US.UTF-8', 04:28:56 INFO - 'LANGUAGE': 'en_US:en', 04:28:56 INFO - 'LOGNAME': 'cltbld', 04:28:56 INFO - 'MAIL': '/var/mail/cltbld', 04:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:56 INFO - 'MOZ_NO_REMOTE': '1', 04:28:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:56 INFO - 'NO_EM_RESTART': '1', 04:28:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:56 INFO - 'PWD': '/builds/slave/test', 04:28:56 INFO - 'SHELL': '/bin/bash', 04:28:56 INFO - 'SHLVL': '1', 04:28:56 INFO - 'TERM': 'linux', 04:28:56 INFO - 'TMOUT': '86400', 04:28:56 INFO - 'USER': 'cltbld', 04:28:56 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:56 INFO - '_': '/tools/buildbot/bin/python'} 04:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:28: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 04:28: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 04:28: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 04:28: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 04:28: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 04:28: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 04:28:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:28:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:28:59 INFO - Installing collected packages: mozsystemmonitor 04:28:59 INFO - Running setup.py install for mozsystemmonitor 04:28:59 INFO - Successfully installed mozsystemmonitor 04:28:59 INFO - Cleaning up... 04:28:59 INFO - Return code: 0 04:28:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:28: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')]} 04:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28: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')]} 04:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:28: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==2.5.1'] in /builds/slave/test/build 04:28: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==2.5.1 04:28:59 INFO - Using env: {'DISPLAY': ':0', 04:28:59 INFO - 'HOME': '/home/cltbld', 04:28:59 INFO - 'LANG': 'en_US.UTF-8', 04:28:59 INFO - 'LANGUAGE': 'en_US:en', 04:28:59 INFO - 'LOGNAME': 'cltbld', 04:28:59 INFO - 'MAIL': '/var/mail/cltbld', 04:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:59 INFO - 'MOZ_NO_REMOTE': '1', 04:28:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:59 INFO - 'NO_EM_RESTART': '1', 04:28:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:59 INFO - 'PWD': '/builds/slave/test', 04:28:59 INFO - 'SHELL': '/bin/bash', 04:28:59 INFO - 'SHLVL': '1', 04:28:59 INFO - 'TERM': 'linux', 04:28:59 INFO - 'TMOUT': '86400', 04:28:59 INFO - 'USER': 'cltbld', 04:28:59 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:59 INFO - '_': '/tools/buildbot/bin/python'} 04:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:59 INFO - Downloading/unpacking jsonschema==2.5.1 04:28:59 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 04:28:59 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 04:28:59 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 04:28:59 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 04:28:59 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 04:28:59 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 04:29:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:29:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:29:01 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 04:29:01 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 04:29:01 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 04:29:01 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 04:29:01 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 04:29:01 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 04:29:01 INFO - Downloading functools32-3.2.3-2.tar.gz 04:29:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:29:01 INFO - warning: no files found matching '*.txt' 04:29:01 INFO - no previously-included directories found matching 'build' 04:29:01 INFO - no previously-included directories found matching 'dist' 04:29:01 INFO - no previously-included directories found matching '.git*' 04:29:01 INFO - Installing collected packages: jsonschema, functools32 04:29:01 INFO - Running setup.py install for functools32 04:29:01 INFO - warning: no files found matching '*.txt' 04:29:01 INFO - no previously-included directories found matching 'build' 04:29:01 INFO - no previously-included directories found matching 'dist' 04:29:01 INFO - no previously-included directories found matching '.git*' 04:29:01 INFO - Successfully installed jsonschema functools32 04:29:01 INFO - Cleaning up... 04:29:01 INFO - Return code: 0 04:29:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:02 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:29:02 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 04:29:02 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 04:29:02 INFO - Using env: {'DISPLAY': ':0', 04:29:02 INFO - 'HOME': '/home/cltbld', 04:29:02 INFO - 'LANG': 'en_US.UTF-8', 04:29:02 INFO - 'LANGUAGE': 'en_US:en', 04:29:02 INFO - 'LOGNAME': 'cltbld', 04:29:02 INFO - 'MAIL': '/var/mail/cltbld', 04:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:02 INFO - 'MOZ_NO_REMOTE': '1', 04:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:02 INFO - 'NO_EM_RESTART': '1', 04:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:02 INFO - 'PWD': '/builds/slave/test', 04:29:02 INFO - 'SHELL': '/bin/bash', 04:29:02 INFO - 'SHLVL': '1', 04:29:02 INFO - 'TERM': 'linux', 04:29:02 INFO - 'TMOUT': '86400', 04:29:02 INFO - 'USER': 'cltbld', 04:29:02 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:02 INFO - '_': '/tools/buildbot/bin/python'} 04:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:29:02 INFO - Cleaning up... 04:29:02 INFO - Return code: 0 04:29:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29:02 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')]} 04:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:02 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')]} 04:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:02 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:29:02 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 04:29:02 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 04:29:02 INFO - Using env: {'DISPLAY': ':0', 04:29:02 INFO - 'HOME': '/home/cltbld', 04:29:02 INFO - 'LANG': 'en_US.UTF-8', 04:29:02 INFO - 'LANGUAGE': 'en_US:en', 04:29:02 INFO - 'LOGNAME': 'cltbld', 04:29:02 INFO - 'MAIL': '/var/mail/cltbld', 04:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:02 INFO - 'MOZ_NO_REMOTE': '1', 04:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:02 INFO - 'NO_EM_RESTART': '1', 04:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:02 INFO - 'PWD': '/builds/slave/test', 04:29:02 INFO - 'SHELL': '/bin/bash', 04:29:02 INFO - 'SHLVL': '1', 04:29:02 INFO - 'TERM': 'linux', 04:29:02 INFO - 'TMOUT': '86400', 04:29:02 INFO - 'USER': 'cltbld', 04:29:02 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:02 INFO - '_': '/tools/buildbot/bin/python'} 04:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:02 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:04 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:29:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 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 04:29:04 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 04:29:05 INFO - Downloading docopt-0.6.1.tar.gz 04:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:29:05 INFO - Installing collected packages: blobuploader, requests, docopt 04:29:05 INFO - Running setup.py install for blobuploader 04:29:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:29:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:29:05 INFO - Running setup.py install for requests 04:29:05 INFO - Running setup.py install for docopt 04:29:05 INFO - Successfully installed blobuploader requests docopt 04:29:05 INFO - Cleaning up... 04:29:05 INFO - Return code: 0 04:29:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:05 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:29:05 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 04:29:05 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 04:29:05 INFO - Using env: {'DISPLAY': ':0', 04:29:05 INFO - 'HOME': '/home/cltbld', 04:29:05 INFO - 'LANG': 'en_US.UTF-8', 04:29:05 INFO - 'LANGUAGE': 'en_US:en', 04:29:05 INFO - 'LOGNAME': 'cltbld', 04:29:05 INFO - 'MAIL': '/var/mail/cltbld', 04:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:05 INFO - 'MOZ_NO_REMOTE': '1', 04:29:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:05 INFO - 'NO_EM_RESTART': '1', 04:29:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:05 INFO - 'PWD': '/builds/slave/test', 04:29:05 INFO - 'SHELL': '/bin/bash', 04:29:05 INFO - 'SHLVL': '1', 04:29:05 INFO - 'TERM': 'linux', 04:29:05 INFO - 'TMOUT': '86400', 04:29:05 INFO - 'USER': 'cltbld', 04:29:05 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:05 INFO - '_': '/tools/buildbot/bin/python'} 04:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:06 INFO - Running setup.py (path:/tmp/pip-FPf5xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:06 INFO - Running setup.py (path:/tmp/pip-L3fRns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:06 INFO - Running setup.py (path:/tmp/pip-T5qa3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:06 INFO - Running setup.py (path:/tmp/pip-sX1QNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:06 INFO - Running setup.py (path:/tmp/pip-DUzmof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:06 INFO - Running setup.py (path:/tmp/pip-HC_Q6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:06 INFO - Running setup.py (path:/tmp/pip-TukmSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:06 INFO - Running setup.py (path:/tmp/pip-wW_130-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:06 INFO - Running setup.py (path:/tmp/pip-jyk2kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:06 INFO - Running setup.py (path:/tmp/pip-wL52ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:06 INFO - Running setup.py (path:/tmp/pip-oVxfuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:06 INFO - Running setup.py (path:/tmp/pip-9sEegO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:06 INFO - Running setup.py (path:/tmp/pip-pB6QqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:07 INFO - Running setup.py (path:/tmp/pip-VomdVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:07 INFO - Running setup.py (path:/tmp/pip-k0wwv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:07 INFO - Running setup.py (path:/tmp/pip-8Spaj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:07 INFO - Running setup.py (path:/tmp/pip-G0fP35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:29:07 INFO - Running setup.py install for manifestparser 04:29:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:29:07 INFO - Running setup.py install for mozcrash 04:29:07 INFO - Running setup.py install for mozdebug 04:29:07 INFO - Running setup.py install for mozdevice 04:29:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:29:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:29:07 INFO - Running setup.py install for mozfile 04:29:07 INFO - Running setup.py install for mozhttpd 04:29:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:29:07 INFO - Running setup.py install for mozinfo 04:29:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Running setup.py install for mozInstall 04:29:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Running setup.py install for mozleak 04:29:08 INFO - Running setup.py install for mozlog 04:29:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Running setup.py install for moznetwork 04:29:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Running setup.py install for mozprocess 04:29:08 INFO - Running setup.py install for mozprofile 04:29:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Running setup.py install for mozrunner 04:29:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:08 INFO - Running setup.py install for mozscreenshot 04:29:08 INFO - Running setup.py install for moztest 04:29:09 INFO - Running setup.py install for mozversion 04:29:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:09 INFO - Cleaning up... 04:29:09 INFO - Return code: 0 04:29:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:09 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')]} 04:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:09 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')]} 04:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:09 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:29:09 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 04:29:09 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 04:29:09 INFO - Using env: {'DISPLAY': ':0', 04:29:09 INFO - 'HOME': '/home/cltbld', 04:29:09 INFO - 'LANG': 'en_US.UTF-8', 04:29:09 INFO - 'LANGUAGE': 'en_US:en', 04:29:09 INFO - 'LOGNAME': 'cltbld', 04:29:09 INFO - 'MAIL': '/var/mail/cltbld', 04:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:09 INFO - 'MOZ_NO_REMOTE': '1', 04:29:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:09 INFO - 'NO_EM_RESTART': '1', 04:29:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:09 INFO - 'PWD': '/builds/slave/test', 04:29:09 INFO - 'SHELL': '/bin/bash', 04:29:09 INFO - 'SHLVL': '1', 04:29:09 INFO - 'TERM': 'linux', 04:29:09 INFO - 'TMOUT': '86400', 04:29:09 INFO - 'USER': 'cltbld', 04:29:09 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:09 INFO - '_': '/tools/buildbot/bin/python'} 04:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:09 INFO - Running setup.py (path:/tmp/pip-1_eYW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:09 INFO - Running setup.py (path:/tmp/pip-l8Ja9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:09 INFO - Running setup.py (path:/tmp/pip-ogh_3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:09 INFO - Running setup.py (path:/tmp/pip-MB3tIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:09 INFO - Running setup.py (path:/tmp/pip-6Wa_8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:09 INFO - Running setup.py (path:/tmp/pip-pB8vIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:09 INFO - Running setup.py (path:/tmp/pip-30oHIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:09 INFO - Running setup.py (path:/tmp/pip-eb0mcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:09 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)) 04:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:09 INFO - Running setup.py (path:/tmp/pip-ZTLLZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:10 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)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:10 INFO - Running setup.py (path:/tmp/pip-OceldB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:10 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)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:10 INFO - Running setup.py (path:/tmp/pip-hJvqnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:10 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)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:10 INFO - Running setup.py (path:/tmp/pip-PchKkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:10 INFO - Running setup.py (path:/tmp/pip-bcLSX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:10 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)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:10 INFO - Running setup.py (path:/tmp/pip-RdJo6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:10 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)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:10 INFO - Running setup.py (path:/tmp/pip-82GeyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:10 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)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:10 INFO - Running setup.py (path:/tmp/pip-0Gb7UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:10 INFO - Running setup.py (path:/tmp/pip-pmvbBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:10 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)) 04:29:10 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)) 04:29:10 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)) 04:29:10 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)) 04:29:10 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)) 04:29:10 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)) 04:29:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 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 04:29:12 INFO - Downloading blessings-1.6.tar.gz 04:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:29:12 INFO - Installing collected packages: blessings 04:29:12 INFO - Running setup.py install for blessings 04:29:12 INFO - Successfully installed blessings 04:29:12 INFO - Cleaning up... 04:29:12 INFO - Return code: 0 04:29:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:29:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:13 INFO - Reading from file tmpfile_stdout 04:29:13 INFO - Current package versions: 04:29:13 INFO - argparse == 1.2.1 04:29:13 INFO - blessings == 1.6 04:29:13 INFO - blobuploader == 1.2.4 04:29:13 INFO - docopt == 0.6.1 04:29:13 INFO - functools32 == 3.2.3-2 04:29:13 INFO - jsonschema == 2.5.1 04:29:13 INFO - manifestparser == 1.1 04:29:13 INFO - mozInstall == 1.12 04:29:13 INFO - mozcrash == 1.0 04:29:13 INFO - mozdebug == 0.1 04:29:13 INFO - mozdevice == 0.49 04:29:13 INFO - mozfile == 1.2 04:29:13 INFO - mozhttpd == 0.7 04:29:13 INFO - mozinfo == 0.9 04:29:13 INFO - mozleak == 0.1 04:29:13 INFO - mozlog == 3.4 04:29:13 INFO - moznetwork == 0.27 04:29:13 INFO - mozprocess == 0.24 04:29:13 INFO - mozprofile == 0.28 04:29:13 INFO - mozrunner == 6.13 04:29:13 INFO - mozscreenshot == 0.1 04:29:13 INFO - mozsystemmonitor == 0.3 04:29:13 INFO - moztest == 0.8 04:29:13 INFO - mozversion == 1.4 04:29:13 INFO - psutil == 3.1.1 04:29:13 INFO - requests == 1.2.3 04:29:13 INFO - wsgiref == 0.1.2 04:29:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:13 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')]} 04:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:13 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')]} 04:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:13 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:29:13 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 04:29:13 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 04:29:13 INFO - Using env: {'DISPLAY': ':0', 04:29:13 INFO - 'HOME': '/home/cltbld', 04:29:13 INFO - 'LANG': 'en_US.UTF-8', 04:29:13 INFO - 'LANGUAGE': 'en_US:en', 04:29:13 INFO - 'LOGNAME': 'cltbld', 04:29:13 INFO - 'MAIL': '/var/mail/cltbld', 04:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:13 INFO - 'MOZ_NO_REMOTE': '1', 04:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:13 INFO - 'NO_EM_RESTART': '1', 04:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:13 INFO - 'PWD': '/builds/slave/test', 04:29:13 INFO - 'SHELL': '/bin/bash', 04:29:13 INFO - 'SHLVL': '1', 04:29:13 INFO - 'TERM': 'linux', 04:29:13 INFO - 'TMOUT': '86400', 04:29:13 INFO - 'USER': 'cltbld', 04:29:13 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:13 INFO - '_': '/tools/buildbot/bin/python'} 04:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 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)) 04:29:13 INFO - Cleaning up... 04:29:13 INFO - Return code: 0 04:29:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:29:13 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')]} 04:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:13 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')]} 04:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:13 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 0x20061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2378630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, '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': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', '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 04:29:13 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 04:29:13 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 04:29:13 INFO - Using env: {'DISPLAY': ':0', 04:29:13 INFO - 'HOME': '/home/cltbld', 04:29:13 INFO - 'LANG': 'en_US.UTF-8', 04:29:13 INFO - 'LANGUAGE': 'en_US:en', 04:29:13 INFO - 'LOGNAME': 'cltbld', 04:29:13 INFO - 'MAIL': '/var/mail/cltbld', 04:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:13 INFO - 'MOZ_NO_REMOTE': '1', 04:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:13 INFO - 'NO_EM_RESTART': '1', 04:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:13 INFO - 'PWD': '/builds/slave/test', 04:29:13 INFO - 'SHELL': '/bin/bash', 04:29:13 INFO - 'SHLVL': '1', 04:29:13 INFO - 'TERM': 'linux', 04:29:13 INFO - 'TMOUT': '86400', 04:29:13 INFO - 'USER': 'cltbld', 04:29:13 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:13 INFO - '_': '/tools/buildbot/bin/python'} 04:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:29:13 INFO - Cleaning up... 04:29:13 INFO - Return code: 0 04:29:13 INFO - Running post-action listener: _resource_record_post_action 04:29:13 INFO - Running post-action listener: _start_resource_monitoring 04:29:13 INFO - Starting resource monitoring. 04:29:13 INFO - [mozharness: 2017-02-09 12:29:13.583835Z] Finished create-virtualenv step (success) 04:29:13 INFO - [mozharness: 2017-02-09 12:29:13.584292Z] Running install step. 04:29:13 INFO - Running pre-action listener: _resource_record_pre_action 04:29:13 INFO - Running main action method: install 04:29:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:13 INFO - Reading from file tmpfile_stdout 04:29:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:29:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:29:13 INFO - Reading from file tmpfile_stdout 04:29:13 INFO - Output received: 04:29:13 INFO - Usage: mozinstall [options] installer 04:29:13 INFO - Options: 04:29:13 INFO - -h, --help show this help message and exit 04:29:13 INFO - -d DEST, --destination=DEST 04:29:13 INFO - Directory to install application into. [default: 04:29:13 INFO - "/builds/slave/test"] 04:29:13 INFO - --app=APP Application being installed. [default: firefox] 04:29:13 INFO - mkdir: /builds/slave/test/build/application 04:29:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:29:30 INFO - Reading from file tmpfile_stdout 04:29:30 INFO - Output received: 04:29:30 INFO - /builds/slave/test/build/application/firefox/firefox 04:29:30 INFO - Running post-action listener: _resource_record_post_action 04:29:30 INFO - [mozharness: 2017-02-09 12:29:30.411801Z] Finished install step (success) 04:29:30 INFO - [mozharness: 2017-02-09 12:29:30.412048Z] Running run-tests step. 04:29:30 INFO - Running pre-action listener: _resource_record_pre_action 04:29:30 INFO - Running main action method: run_tests 04:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:29:30 INFO - Python 2.7.3 04:29:30 INFO - Return code: 0 04:29:30 INFO - grabbing minidump binary from tooltool 04:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:30 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 0x2401850>, '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 0x23766d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:29:30 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 04:29:30 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 04:29:30 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 04:29:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:29:30 INFO - Return code: 0 04:29:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:29:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:29:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:29:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:29:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 04:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --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 04:29:30 INFO - Using env: {'DISPLAY': ':0', 04:29:30 INFO - 'HOME': '/home/cltbld', 04:29:30 INFO - 'LANG': 'en_US.UTF-8', 04:29:30 INFO - 'LANGUAGE': 'en_US:en', 04:29:30 INFO - 'LOGNAME': 'cltbld', 04:29:30 INFO - 'MAIL': '/var/mail/cltbld', 04:29:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:29:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:30 INFO - 'MOZ_NO_REMOTE': '1', 04:29:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:30 INFO - 'NO_EM_RESTART': '1', 04:29:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:30 INFO - 'PWD': '/builds/slave/test', 04:29:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:29:30 INFO - 'SHELL': '/bin/bash', 04:29:30 INFO - 'SHLVL': '1', 04:29:30 INFO - 'TERM': 'linux', 04:29:30 INFO - 'TMOUT': '86400', 04:29:30 INFO - 'USER': 'cltbld', 04:29:30 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734', 04:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:30 INFO - '_': '/tools/buildbot/bin/python'} 04:29:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 04:29:30 INFO - mozversion application_buildid: 20170209113607 04:29:30 INFO - mozversion application_changeset: 75bda9957ea872ab71fbfa63c8f4d57e2458afb6 04:29:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:29:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:29:30 INFO - mozversion application_name: Firefox 04:29:30 INFO - mozversion application_remotingname: firefox-dev 04:29:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:29:30 INFO - mozversion application_vendor: Mozilla 04:29:30 INFO - mozversion application_version: 53.0a2 04:29:30 INFO - mozversion platform_buildid: 20170209113607 04:29:30 INFO - mozversion platform_changeset: 75bda9957ea872ab71fbfa63c8f4d57e2458afb6 04:29:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:29:30 INFO - mozversion platform_version: 53.0a2 04:29:30 INFO - using testdate: 1486643370 04:29:30 INFO - actual date: 1486643370 04:29:30 INFO - starting webserver on 'localhost:40830' 04:29:30 INFO - SUITE-START | Running 2 tests 04:29:30 INFO - TEST-START | dromaeo_css 04:29:30 INFO - operating with platform_type : linux_ 04:29:30 INFO - Initialising browser for dromaeo_css test... 04:29:30 INFO - TEST-INFO | started process 32656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAmkmT/profile http://localhost:40830/getInfo.html) 04:29:34 INFO - PROCESS | 32656 | __metrics Screen width/height:1600/1200 04:29:34 INFO - PROCESS | 32656 | colorDepth:24 04:29:34 INFO - PROCESS | 32656 | Browser inner width/height: 1024/697 04:29:34 INFO - PROCESS | 32656 | __metrics 04:29:35 INFO - PROCESS | 32656 | 1486643375622 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:29:35 INFO - PROCESS | 32656 | 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" 04:29:35 INFO - PROCESS | 32656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:29:36 INFO - TEST-INFO | 32656: exit 0 04:29:36 INFO - Browser initialized. 04:29:36 INFO - Running cycle 1/1 for dromaeo_css test... 04:29:36 INFO - TEST-INFO | started process 32747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAmkmT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:48 INFO - PROCESS | 32747 | RSS: Main: 137834496 04:29:48 INFO - PROCESS | 32747 | 04:32:48 INFO - PROCESS | 32747 | Cycle 1(1): loaded http://localhost:40830/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40830/tests/dromaeo/cssquery-ext.html) 04:32:50 INFO - PROCESS | 32747 | RSS: Main: 238944256 04:32:50 INFO - PROCESS | 32747 | 04:34:38 INFO - PROCESS | 32747 | ************************* 04:34:38 INFO - PROCESS | 32747 | A coding exception was thrown and uncaught in a Task. 04:34:38 INFO - PROCESS | 32747 | 04:34:38 ERROR - PROCESS | 32747 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:34:38 INFO - PROCESS | 32747 | Full stack: 04:34:38 INFO - PROCESS | 32747 | ************************* 04:35:52 INFO - PROCESS | 32747 | Cycle 1(1): loaded http://localhost:40830/tests/dromaeo/cssquery-ext.html (next: http://localhost:40830/tests/dromaeo/cssquery-jquery.html) 04:35:52 INFO - PROCESS | 32747 | RSS: Main: 224448512 04:35:52 INFO - PROCESS | 32747 | 04:38:54 INFO - PROCESS | 32747 | Cycle 1(1): loaded http://localhost:40830/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40830/tests/dromaeo/cssquery-mootools.html) 04:38:55 INFO - PROCESS | 32747 | RSS: Main: 171282432 04:38:55 INFO - PROCESS | 32747 | 04:41:57 INFO - PROCESS | 32747 | Cycle 1(1): loaded http://localhost:40830/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40830/tests/dromaeo/cssquery-prototype.html) 04:41:58 INFO - PROCESS | 32747 | RSS: Main: 171126784 04:41:58 INFO - PROCESS | 32747 | 04:45:00 INFO - PROCESS | 32747 | Cycle 1(1): loaded http://localhost:40830/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40830/tests/dromaeo/cssquery-yui.html) 04:45:01 INFO - PROCESS | 32747 | RSS: Main: 171851776 04:45:01 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | Cycle 1(1): loaded http://localhost:40830/tests/dromaeo/cssquery-yui.html (next: http://localhost:40830/tests/dromaeo/cssquery-dojo.html) 04:48:04 INFO - PROCESS | 32747 | RSS: Main: 264904704 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | __start_tp_report 04:48:04 INFO - PROCESS | 32747 | _x_x_mozilla_page_load 04:48:04 INFO - PROCESS | 32747 | _x_x_mozilla_page_load_details 04:48:04 INFO - PROCESS | 32747 | |i|pagename|runs| 04:48:04 INFO - PROCESS | 32747 | |0;dojo.html;3304.4878043903195;3347.5313456116146;3353.4131526982774;3380.6281309056017;3392.7964322140674;5495.175723641456;5536.501714845673;5544.251526043983;5558.277423934883;5563.610547261688;5471.781128754855;5578.470045345685;5594.636348637336;5610.354809196943;5622.437756224375;5070.112730272206;5093.821716239932;5111.131107711698;5163.406208286039;5171.508706672879;4879.341288926003;5095.643304968635;5130.025295193901;5152.124138896378;5153.922691159617;4869.099216644914;4973.602111831063;4985.975070124644;5017.749112544377;5085.898282034357;5068.3917929848285;5109;5110.948890511085;5118.616103792212;5145.9485405146215;4955.628327875388;5020.497950204986;5072.72100034498;5085.1355269604255;5089.312942752718;5359.490848369438;5403.864903377408;5412.512873841334;5466.426025267329;5473.425290344536;5874.441928016831;5890.97054514729;5919.052951527735;5922.763089476416;5951.791687290967;5951.137085122633;5961.672108034057;5964.761409543613;5973.044312909957;5978.55160862937;5425.701586412746;5503.669779813224;5525.254090697785;5533.059379905426;5570.220169176318;6006.969965150233;6060.636361818305;6064;6080.179175811209;6101.908471372933;5809.186713860063;5853.882922341614;5863.237779088777;5866.941330586639;5868.23712917318;5670.5747068969995;5693.089105743061;5746.913796293059;5938.317093534299;5939.910901336483;5750;5799.652020878761;5824.155497452845;5828.6502809831545;5866.560007999417;5811.709414529341;5813.7383817728305;5814.825555233349;5819.53443724501;5829.883402332014;4433.933490997638;4480.798364073625;4494.775261236925;4502.099580083996;4514;3999.3601023836045;4020.2361551305157;4031.5363733170475;4079.796010199478;4104.15864747726;5669.922714684197;5673.3475650299915;5675.602707810413;5676.4323567642905;5677.205191273225;5694.629849059798;5795.507381872504;5801.593888427769;5930.9406905931255;5933.940660593339;5573.219749235111;5636.549076073904;5641.182028605829;5649.406812284733;5673.687947162945;4976.527229913152;4982.825601103944;4988.226824842155;4988.625853060962;5024.070546948826;5910.911336330045;6060.030395136757;6109.877802443926;6114.633122012605;6132.969335153296;5714.257146570919;5876.647401155944;5896.941030589639;5928.49607783326;6031;6201.875962480815;6202.596831205957;6203.565750397429;6220.968895155495;6227.06594010902;5833.79581714638;5889.175515427758;5891.970540147443;5893.086571581412;5929.584929054925;5775.7400916957995;5786.3056433228285;5793.79721709755;5806;5823.4467725566;5669.688167150846;5702.344230413555;5722.599418040697;5732.914006289992;5743.827685169452;5707.514861236676;5713.028785106459;5717.285339332583;5718.971405143114;5719.5138413235345;5577.6653400796085;5645.294338207724;5677.630953987978;5708.172315014383;5712.257406537124;5836.620619659709;5844.853878653068;5846.707664616837;5852.736626851717;5889.411058894077;5704.258446401941;5706.372299047019;5708.942910571007;5709.857253568694;5714.828555143353 04:48:04 INFO - PROCESS | 32747 | |1;ext.html;544.6299206652603;578.3378032153184;610.5695484683297;612.4824523277831;652.057776512939;35925;47292.34476793313;48809.267860982116;48864;49010.75494622531;51355.22967155496;51736.223956640584;51738.22392664113;51831.74084129574;51939;8687.218150366465;8830.558472076402;9460.432374057562;9649.179819715333;9657.32398732089;10735.892641073606;10904.61833835816;10911.618093366733;11327.093832493421;11339.206255562114;11431.5427382905;11504.137189710764;11517.654470365902;11518.481668324945;11522.135839812006;11370.886291137107;11400.543978240861;11403.087752979825;11418.54325826966;11429.542818287258;11288.774224515464;11361.375124368156;11367.829482557769;11376.943115284454;11385;47651.5234847654;48021.279680804815;48033.51966480325;48042.51957480415;48046.5195348049;4288.270993931039;4485.551444855521;4519.977400113011;4522.253828118356;4722.7402492862875;32131.678683213337;32217.355652887047;32528.512072318936;32742;33708.49437258443;4581.266997280452;4635.142498637762;4636.605888499482;4651.162790697673;4680.976595117037;344332;346003;346492.1403143871;346657;350295;63606.68196658987;64275;64288.35711642917;64496;64591.354086459476;65901;66069.33930660631;66297;66400;66660.66669666718;18440;19095.427137185907;19102.522436939187;19108.33120840764;19111.617767644846;12975.740485190432;13240.53658121961;13369.799453008212;13548.932255338661;13987.300634968415;19429.22283108688;19441.319553815832;19445.902770486056;19505.707414388795;19556.80443195578;7153.101559650003;7265.420775624682;7337.706491740378;7375.778726638209;7423.44324175689;926.6710317837205;935.6818681648136;936.9344145909721;947.4126041854095;951.471933077135;31779.205519861727;32526.34947301088;32699.346013079605;33270.83364583162;35070.27563943382;1070.2829104500004;1073.044989958937;1089.7330154112135;1092.257265059751;1107.4739498738036;16190.514244615786;16608.750868736977;16929.15354232308;16949.661006779796;16982.660346792996;580460;585175;585451;585522;585547;61588.38411615916;62021;62125.37874621196;62169.9999999991;62214;1279.7120647854158;1408.739383214088;1421.6943357178257;1447.3920953199681;1448.2613866927943;29776.404471910875;30810;31223;31567.684323156474;31584.526232106997;54369;55686;55818;55826;55837;370512;371646;373192;373406.06375744665;374084;29707.257318567208;30483.7806487738;30636;30664.080077597708;30688.386232275676;44383.55616443924;47219;47751.28373074476;48753.75623121862;49036;39915.2016959665;40047.79976100218;40570.391444127774;41138.79430602828;42023;39821.60178398295;39954.200915982095;40924.386134207416;41041.384379233736;41379.1724165521;39725;39756.403653945796;39792;39862.20275594414;40095.799521002205;33261.50107748434;35427.82286088553;35686.64313356833;35745.67653102644;35989.82005090063;27143.59284610693;27406.5889011669;27566;27614.65275451609;27855.72144278612 04:48:04 INFO - PROCESS | 32747 | |2;jquery.html;380.41405478353573;438.776224125696;443.2553310438463;450.26157102352136;457.38938517079697;19881.900590497044;21432.678509822366;21479.463013424633;21599;21653.45866353342;21995.23016694416;22295.108195672194;22397.888010559924;22465;22570.887145564247;5890.175375447441;5898.587098903068;5992.7902523411685;6063.333033366326;6108.664023478706;4529.479109902354;4782.7608619569055;5785.479306862381;5857.707114644272;5866.88266234675;4753.382060332169;4758.072175925696;6470.967645161768;6694.163229596301;6730.629815360153;6603.580230250495;6660.267370589231;6675.065490831287;6693.598384096946;6851.04085428038;5899.893610113555;5937.7921772737745;5942.197803296543;5951.077582974646;5953.315368732608;26443.867780661167;26454.206373808818;26479.205623831316;26516.867415662797;26671.73328266712;3263.9347213055844;3299.686529779691;3327.8502467389026;3386.915327116817;3404.114930118162;16166.191690415431;16300.83699163005;16307.592310192222;16314.184290785532;16317.673646527122;2011.416689160142;2043.6832290994919;2052.0252879882087;2087.405089549486;2140.165335519149;97907;99575.99999999927;99975;100577.4971125147;101447;10595.940405959342;11074.5570177192;11298.887011129947;11308.943455282671;11709.88290117105;15549;16305.429309974093;16334.264958076677;17036.14819259032;17125.828741712674;8826.55867206637;8933.776655583662;8939.508327041945;9071.228945539702;9071.863922041175;8567.785805354915;8611.440256383315;8613.913860861436;8747.737567872973;8857.512836793909;7486.326230639269;7615.086189657277;7652.273034061755;7733.883991740128;7746.109197442253;2095.847283993797;2141.346889198809;2152.967705484419;2180.160638154322;2184.224600266914;894.5750768385095;900.6262401103469;906.5467266366817;915.8168366326761;931.3247895276006;21097.156113755278;21362;21676.24133155332;21778.455538611346;21781.23765668194;903.5301643145527;903.6340282185726;918.7932715139041;927.6335847340263;939.6992962252014;11518.424078796026;12054.835344956113;12185.634430967086;12364.1963272387;12387.380630968415;492219;494888;495152;730369;805208;110765;119533;120585;121861;124361;1106.2145876427826;1142.0806250967885;1167.9416029198505;1171.9589814356457;1174.0607513988775;19127.713084304025;19358.806411936264;19378.127984240462;19451.902740486774;19540.511487212934;44462.77768611136;44628.77685611681;45299.320510193036;45373.54626453825;46158.30762538632;60909.69545152246;64437.67781161064;65409.34590654032;66783.3321666777;67004.3299566998;8804;8977.057408971963;9568.234541236545;9595.568199430903;9918.702438926844;11911.940440297743;11952.282863028246;12431.62705118848;13175.14361566495;13466.797998030233;10956.561737530728;11563.595274165364;11728.354940478355;11928.4035798208;12387.442565084413;11500.25248358854;11547.480363383836;11810.586629467927;12068.396580171131;12308.138430310151;11486.19596628228;11751.118666100248;11865.940670296593;11951.163418560966;12252.387380631111;7651.464397492122;8848.380613357262;9019.549022548978;9026.45841249527;9523.333366664267;11816.113791465847;12308.938455307667;12315;12389.814152787896;12635.810462842881 04:48:04 INFO - PROCESS | 32747 | |3;mootools.html;1525.229758971719;1527.0608575725928;1528.262386601536;1548.4812587783092;1557.9688406231876;8426.452280601765;9006.054364291753;9011.684591039315;9029.187373136416;9032.68385606504;7715.074191097076;7921.603919804015;7924.445288829785;8152.225538573841;8807.559622018905;2389.7371289158177;2461.692288463942;2465.506898620283;2488;2489.601663733803;2437.695288088989;2464.2484042367073;2467.938301542458;2475.88858501367;2487.614419764939;2416.359664688859;2420.9757902420924;2438.670779444779;2441.5361081394567;2442.5481285962064;2419.9153029643967;2426.5268272686835;2428.429319110008;2429.927102186937;2441.8046556275544;2393.114547617393;2410.252821625284;2449.3876530867283;2450.8529488230765;2459.8770061496853;9134.223590994707;9185.17333439987;9185.954070229673;9189.954050229773;9200.987891331948;5410.891782164374;5559.666420014772;5562.3881373048935;5568.109102543614;5569.10894256917;3268.3953468608147;3416.333814906094;3435.175557866119;3475.982620086909;3513.1919658478437;1335.44578999715;1435.8277006759151;2193.517426166241;2254.109626697462;2259.830512711537;12519.3740312984;12592.874071259354;12600.180988235736;12633.178843375152;12650.367481625974;4552.7495987720995;4752.382190315306;4799;4802.711837289704;4859.1496488114435;10020.198384129251;10040;10052.64815731446;10068.949655251823;10075.395476271447;5542.057850165482;5546.500814926676;5549.750261238254;5560.666360018412;5566.972165139229;4507.932381014288;4997.225430058346;5089.694618322913;5120.078385890501;5201.375834899761;3703.851845926187;3950.111224974358;3995.6603688686787;4048.5344185418467;4058;1144.3934714601276;1146.3694967767694;1151.8329842172839;1158.8899054589801;1162.976740465186;992.1715367667933;1000.1099021870541;1016.2428990402198;1017.5115944346755;1018.4449475036123;6060.424259695322;6220.564560480813;6257.624542527463;6304.464120549805;6485.805425837232;1092.5083712329324;1112.6050252160528;1135.5855112883737;1147.3345459633392;1152.3604399558326;4210.452641156691;4220.746755194698;4293.098449325614;4310.137972405531;4327.307630779061;10765.192610554082;10932.836007459895;11113.388763618079;11119.833202501968;11137.832932506019;10601.628942987112;10817.83773243402;10844.240903136862;10857.239993200401;10890.455477226107;8325.791855203668;8521.488710677379;8550.74347769568;8578.871316930252;8608.91391086081;5856.707164641836;5872.207252021093;5988.790392336247;5994.6403215807195;6026.789062382795;10020.899791001997;10042.146417554297;10074.14369778577;10112.140468060297;10173.491325433552;9707.611695532381;10030.448325342177;10088.949555252177;10274.434906080238;10368.53341599645;7326.3040011198855;7984.960075199587;8133.308668762985;8138.3489320853005;8139.4709343892455;7788.532688038736;8577.228049475578;8643.56782160902;8883.511406872683;8900.42147260426;7870.212978702084;7938.960305198437;7939.722109726132;7972.920270797218;8085.151059138706;7557.130929943127;7850.371970242481;7862.882056769267;7919.366450684047;7997.8400431992195;6887.793366199022;6912.066870972354;6945.201301850352;6986.126734158231;7009.7897063088185;5369.3556773187465;5433.239346491416;5448.673079615236;5461.754221059982;5496.642718223303;5292.5501332387175;5339.839804805862;5387.380451248;5409;5440.374356949002 04:48:04 INFO - PROCESS | 32747 | |4;prototype.html;409.3610515153758;417.92059508693336;419.06497983930876;419.25582091787095;432.4810227726729;5419.810306639269;5423.674579525231;5468.644538105031;5478.260434841305;5481.095619222829;5124.360954108637;5334.653247538917;5370.731463426832;5386.660270632204;5390.676559406428;4045.615666511677;4104.199681062194;4171.749695018309;4227.408162857202;4248.53266140725;4219.818450833772;4225.915481690365;4231.1749208904275;4233.83064677414;4233.978830105845;4200.1599680063955;4207.347861081537;4209.568746626146;4212.4945006599255;4219.346001369778;3962.346212874872;4015.3374693175883;4166.250074986486;4206.810693518783;4209.221294060609;3759.4360845873066;3760.9845341757846;3765.5104836371097;3779.848806047755;3816.46569480273;5252.8686782830355;5281.419043905167;5284.392294886099;5297.940411917593;5302.893942121136;4592.954070459286;4805.567498925114;4860.368152140234;4893.657443978923;4894.972055868272;5610.270664813589;5622.718864056781;5624.734434752189;5630.408807075239;5657.868426314712;4095.467589213413;4098.72520750983;4129.8967525811795;4133.731307465006;4156.397322388267;5744.368119506851;5804.7097645118;5844.697745773667;5903.321118071433;5927.318358388798;4221.261279276115;4226.344916537942;4236.661067114624;4252.48970123581;4267.125239325993;4249.787510624456;4276.764777937244;4285.46431696038;4286.77087914289;4307.956920430755;4711.434627844612;4796.448408433005;4860.198067318924;4887.975560122177;4889.877753056202;5622.156676498558;5630.577706672016;5637.689927054052;5652.982463156589;5660.688662123623;1424.8005279260913;1441.7116576684705;1443.935022923971;1445.1329202478428;1446.2407236200868;959.7936443664506;985.6791898855568;994.557421947241;1004.3271008424373;1011.585250047477;5247.134222853263;5330.626856120112;5377.704226267593;5399.83800485986;5471.37079235885;5754.251947246831;5756.848630273962;5780.872729817729;5803.593748437569;5805.158252053428;5318;5542.778288868481;5552.472515111058;5554.1113421853115;5577.944220557823;2934.662513810939;2942.146777434563;2955.7783166262616;2965.9534920904507;2978.44898693738;5842.766289348378;5849.268841394825;5849.795257165979;5854.648721076749;5882.764619429968;5496.31296087989;5688.971555142198;5703.289013295995;5722.456366645162;5784.50831679312;5760.51035661965;5827.639805878626;5843.474087332161;5849.766009359748;5864.413558644102;5298.2052692096495;5555.805546805843;5568.359638641608;5593.412691667317;5659.603827732019;5430.565554755531;5473.397926228191;5550.639208451437;5563.888722225452;5576.609637325349;5765.365809760839;5794.855128621818;5798.94201057984;5808.82573522795;5818.970905145447;4204.675527208919;4229.043223896734;4296.4414626098405;4309;4378.211921853969;5511.5394420477805;5533.418991005887;5570.44295570456;5616.85957851057;5657.377688454185;5401.351837779492;5405.081136206775;5488.423715509974;5513.283273174462;5517.806876759294;5308.628396012242;5373.543248823894;5387.488188622075;5407.107827208467;5487.6981766003255;5354.78580856777;5376.247325374529;5440.211169380497;5450;5490.286262785812;5411.6211865170635;5445.1832225166545;5538.9169162461785;5548.611597188158;5668.971655141698;5571.9721401392735;5578.2748242728185;5606.831795046155;5615;5618 04:48:04 INFO - PROCESS | 32747 | |5;yui.html;818.5048045932215;818.9385796065296;821.3059964330141;832.6877420967137;836.4395854777299;16324.265408056637;17452.825471745295;17897.28410863567;18114.275428982826;18187.727184092248;16436.17819109043;16497.587560311;17353.305867765273;17458.738118928228;18569.442916712487;716.2443621978811;814.2752949874616;830.8919840420767;831.6507067031831;831.7213733399318;737.1670012885432;746.5781833264189;747.0661672908864;818.8485130250892;840.0255703384075;863.9006514250848;869.9347548933823;870.0212261206143;870.2385412763832;870.4385671242045;865.1824026295153;865.6364326982682;866.0473479172891;866.445474896067;866.7183165471247;538.7010209333819;554.5425024354882;630.914826498426;634.9968250158765;645.441692935612;17227.569310767205;17416.912915435467;17506.387276445264;17569.912150439293;17621.55946101345;14546.636334091627;14851;15104.69516187892;15184.772228416583;15239.771403428957;14138.929305353511;14227.85772142286;14401.20793356365;14435.133891966516;14510.854891451161;4624.144533261324;4673.813047478096;4690.390249267608;4702.435707715096;4771.928421073687;81073;81793.59103204445;82160.58919705363;82209;83839.16160838435;11703.063754899587;11726.765464690829;11743.295402275891;11790.764184716429;12077.879221207852;22524.54950900973;22665.2067177648;22685.659715104288;23350.416239594146;23402.765972340057;8481.194286542892;8687.869681954775;8894.555272236363;9014.63941442348;9132.269418446642;4546.431696037996;4674.672772905932;4696.553827386393;4766.237402015661;4786.976065119652;3844.1927195288745;3951.05174758056;3951.308521008812;4050.9594904051173;4228.04868904494;715.4062128433388;722.3607107709714;725.0320821703085;725.2783480436893;742.1873049011324;819.7950512371907;844.7845799321134;851.9275861551838;861.4615865084322;864.3430992445661;10879.401632910265;11310.717232069099;11566.132540059529;11569;11901.107416943763;849.1720572441943;854.5855260198798;858.1847245117164;859.6002858670623;866.9913300867037;5287.365516138011;5313.57491400687;5418.593605479605;5424.83725488236;5457.617966742369;82858;83599;84120;85118.57440712757;86627.56686216529;56979;72402;72907;73045.6347718258;73407.63296183485;62568.68715656484;62610.373896261364;63926.36073639297;64907.350926491075;66644.33355666479;10181.898181018096;10441.164706823283;10460.267781255543;10489.527971241465;10496.47517624131;44545.55454445414;44905;44905.77547112243;45343.31985020293;45857.77071114623;54983.45016549783;55487;56258.43741562532;57496.13755793583;57526;14128.152310861382;14231.64420889486;14375.353109109907;14977.550673479813;15120.924395377953;15578.766318505004;15715.214239288218;16033.278502467183;16492.505224843273;16592.91703541523;15297.388104476142;15769;16180.271887764844;16366;16905.74641380405;15227.847721523087;16059.839401606301;16439.424620138238;16516;16716.24776885019;14369.13785172893;15309.923450382677;15355.23223838826;15453.768193477332;15883.920580397024;11348.943255283672;11832;12112.394380280775;12460.439280232593;12745;12409.565665201675;12889.742205156032;12974.118752780983;13207.27359995171;13318.600441986757 04:48:04 INFO - PROCESS | 32747 | __end_tp_report 04:48:04 INFO - PROCESS | 32747 | __start_cc_report 04:48:04 INFO - PROCESS | 32747 | _x_x_mozilla_cycle_collect,937 04:48:04 INFO - PROCESS | 32747 | __end_cc_report 04:48:04 INFO - PROCESS | 32747 | __startTimestamp1486644484306__endTimestamp 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | ------- Summary: start ------- 04:48:04 INFO - PROCESS | 32747 | Number of tests: 6 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | [#0] dojo.html Cycles:165 Average:5489.28 Median:5702.34 stddev:599.93 (10.5%) stddev-sans-first:576.77 04:48:04 INFO - PROCESS | 32747 | Values: 3304.5 3347.5 3353.4 3380.6 3392.8 5495.2 5536.5 5544.3 5558.3 5563.6 5471.8 5578.5 5594.6 5610.4 5622.4 5070.1 5093.8 5111.1 5163.4 5171.5 4879.3 5095.6 5130.0 5152.1 5153.9 4869.1 4973.6 4986.0 5017.7 5085.9 5068.4 5109.0 5110.9 5118.6 5145.9 4955.6 5020.5 5072.7 5085.1 5089.3 5359.5 5403.9 5412.5 5466.4 5473.4 5874.4 5891.0 5919.1 5922.8 5951.8 5951.1 5961.7 5964.8 5973.0 5978.6 5425.7 5503.7 5525.3 5533.1 5570.2 6007.0 6060.6 6064.0 6080.2 6101.9 5809.2 5853.9 5863.2 5866.9 5868.2 5670.6 5693.1 5746.9 5938.3 5939.9 5750.0 5799.7 5824.2 5828.7 5866.6 5811.7 5813.7 5814.8 5819.5 5829.9 4433.9 4480.8 4494.8 4502.1 4514.0 3999.4 4020.2 4031.5 4079.8 4104.2 5669.9 5673.3 5675.6 5676.4 5677.2 5694.6 5795.5 5801.6 5930.9 5933.9 5573.2 5636.5 5641.2 5649.4 5673.7 4976.5 4982.8 4988.2 4988.6 5024.1 5910.9 6060.0 6109.9 6114.6 6133.0 5714.3 5876.6 5896.9 5928.5 6031.0 6201.9 6202.6 6203.6 6221.0 6227.1 5833.8 5889.2 5892.0 5893.1 5929.6 5775.7 5786.3 5793.8 5806.0 5823.4 5669.7 5702.3 5722.6 5732.9 5743.8 5707.5 5713.0 5717.3 5719.0 5719.5 5577.7 5645.3 5677.6 5708.2 5712.3 5836.6 5844.9 5846.7 5852.7 5889.4 5704.3 5706.4 5708.9 5709.9 5714.8 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | [#1] ext.html Cycles:180 Average:61101.39 Median:206904.50 stddev:118636.90 (57.3%) stddev-sans-first:118882.58 04:48:04 INFO - PROCESS | 32747 | Values: 544.6 578.3 610.6 612.5 652.1 35925.0 47292.3 48809.3 48864.0 49010.8 51355.2 51736.2 51738.2 51831.7 51939.0 8687.2 8830.6 9460.4 9649.2 9657.3 10735.9 10904.6 10911.6 11327.1 11339.2 11431.5 11504.1 11517.7 11518.5 11522.1 11370.9 11400.5 11403.1 11418.5 11429.5 11288.8 11361.4 11367.8 11376.9 11385.0 47651.5 48021.3 48033.5 48042.5 48046.5 4288.3 4485.6 4520.0 4522.3 4722.7 32131.7 32217.4 32528.5 32742.0 33708.5 4581.3 4635.1 4636.6 4651.2 4681.0 344332.0 346003.0 346492.1 346657.0 350295.0 63606.7 64275.0 64288.4 64496.0 64591.4 65901.0 66069.3 66297.0 66400.0 66660.7 18440.0 19095.4 19102.5 19108.3 19111.6 12975.7 13240.5 13369.8 13548.9 13987.3 19429.2 19441.3 19445.9 19505.7 19556.8 7153.1 7265.4 7337.7 7375.8 7423.4 926.7 935.7 936.9 947.4 951.5 31779.2 32526.3 32699.3 33270.8 35070.3 1070.3 1073.0 1089.7 1092.3 1107.5 16190.5 16608.8 16929.2 16949.7 16982.7 580460.0 585175.0 585451.0 585522.0 585547.0 61588.4 62021.0 62125.4 62170.0 62214.0 1279.7 1408.7 1421.7 1447.4 1448.3 29776.4 30810.0 31223.0 31567.7 31584.5 54369.0 55686.0 55818.0 55826.0 55837.0 370512.0 371646.0 373192.0 373406.1 374084.0 29707.3 30483.8 30636.0 30664.1 30688.4 44383.6 47219.0 47751.3 48753.8 49036.0 39915.2 40047.8 40570.4 41138.8 42023.0 39821.6 39954.2 40924.4 41041.4 41379.2 39725.0 39756.4 39792.0 39862.2 40095.8 33261.5 35427.8 35686.6 35745.7 35989.8 27143.6 27406.6 27566.0 27614.7 27855.7 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | [#2] jquery.html Cycles:180 Average:34573.05 Median:26594.30 stddev:102338.55 (384.8%) stddev-sans-first:102593.43 04:48:04 INFO - PROCESS | 32747 | Values: 380.4 438.8 443.3 450.3 457.4 19881.9 21432.7 21479.5 21599.0 21653.5 21995.2 22295.1 22397.9 22465.0 22570.9 5890.2 5898.6 5992.8 6063.3 6108.7 4529.5 4782.8 5785.5 5857.7 5866.9 4753.4 4758.1 6471.0 6694.2 6730.6 6603.6 6660.3 6675.1 6693.6 6851.0 5899.9 5937.8 5942.2 5951.1 5953.3 26443.9 26454.2 26479.2 26516.9 26671.7 3263.9 3299.7 3327.9 3386.9 3404.1 16166.2 16300.8 16307.6 16314.2 16317.7 2011.4 2043.7 2052.0 2087.4 2140.2 97907.0 99576.0 99975.0 100577.5 101447.0 10595.9 11074.6 11298.9 11308.9 11709.9 15549.0 16305.4 16334.3 17036.1 17125.8 8826.6 8933.8 8939.5 9071.2 9071.9 8567.8 8611.4 8613.9 8747.7 8857.5 7486.3 7615.1 7652.3 7733.9 7746.1 2095.8 2141.3 2153.0 2180.2 2184.2 894.6 900.6 906.5 915.8 931.3 21097.2 21362.0 21676.2 21778.5 21781.2 903.5 903.6 918.8 927.6 939.7 11518.4 12054.8 12185.6 12364.2 12387.4 492219.0 494888.0 495152.0 730369.0 805208.0 110765.0 119533.0 120585.0 121861.0 124361.0 1106.2 1142.1 1167.9 1172.0 1174.1 19127.7 19358.8 19378.1 19451.9 19540.5 44462.8 44628.8 45299.3 45373.5 46158.3 60909.7 64437.7 65409.3 66783.3 67004.3 8804.0 8977.1 9568.2 9595.6 9918.7 11911.9 11952.3 12431.6 13175.1 13466.8 10956.6 11563.6 11728.4 11928.4 12387.4 11500.3 11547.5 11810.6 12068.4 12308.1 11486.2 11751.1 11865.9 11951.2 12252.4 7651.5 8848.4 9019.5 9026.5 9523.3 11816.1 12308.9 12315.0 12389.8 12635.8 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | [#3] mootools.html Cycles:180 Average:5917.73 Median:4301.62 stddev:3256.90 (75.7%) stddev-sans-first:3249.31 04:48:04 INFO - PROCESS | 32747 | Values: 1525.2 1527.1 1528.3 1548.5 1558.0 8426.5 9006.1 9011.7 9029.2 9032.7 7715.1 7921.6 7924.4 8152.2 8807.6 2389.7 2461.7 2465.5 2488.0 2489.6 2437.7 2464.2 2467.9 2475.9 2487.6 2416.4 2421.0 2438.7 2441.5 2442.5 2419.9 2426.5 2428.4 2429.9 2441.8 2393.1 2410.3 2449.4 2450.9 2459.9 9134.2 9185.2 9186.0 9190.0 9201.0 5410.9 5559.7 5562.4 5568.1 5569.1 3268.4 3416.3 3435.2 3476.0 3513.2 1335.4 1435.8 2193.5 2254.1 2259.8 12519.4 12592.9 12600.2 12633.2 12650.4 4552.7 4752.4 4799.0 4802.7 4859.1 10020.2 10040.0 10052.6 10068.9 10075.4 5542.1 5546.5 5549.8 5560.7 5567.0 4507.9 4997.2 5089.7 5120.1 5201.4 3703.9 3950.1 3995.7 4048.5 4058.0 1144.4 1146.4 1151.8 1158.9 1163.0 992.2 1000.1 1016.2 1017.5 1018.4 6060.4 6220.6 6257.6 6304.5 6485.8 1092.5 1112.6 1135.6 1147.3 1152.4 4210.5 4220.7 4293.1 4310.1 4327.3 10765.2 10932.8 11113.4 11119.8 11137.8 10601.6 10817.8 10844.2 10857.2 10890.5 8325.8 8521.5 8550.7 8578.9 8608.9 5856.7 5872.2 5988.8 5994.6 6026.8 10020.9 10042.1 10074.1 10112.1 10173.5 9707.6 10030.4 10088.9 10274.4 10368.5 7326.3 7985.0 8133.3 8138.3 8139.5 7788.5 8577.2 8643.6 8883.5 8900.4 7870.2 7939.0 7939.7 7972.9 8085.2 7557.1 7850.4 7862.9 7919.4 7997.8 6887.8 6912.1 6945.2 6986.1 7009.8 5369.4 5433.2 5448.7 5461.8 5496.6 5292.6 5339.8 5387.4 5409.0 5440.4 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | [#4] prototype.html Cycles:180 Average:4719.00 Median:5389.08 stddev:1350.88 (25.1%) stddev-sans-first:1315.37 04:48:04 INFO - PROCESS | 32747 | Values: 409.4 417.9 419.1 419.3 432.5 5419.8 5423.7 5468.6 5478.3 5481.1 5124.4 5334.7 5370.7 5386.7 5390.7 4045.6 4104.2 4171.7 4227.4 4248.5 4219.8 4225.9 4231.2 4233.8 4234.0 4200.2 4207.3 4209.6 4212.5 4219.3 3962.3 4015.3 4166.3 4206.8 4209.2 3759.4 3761.0 3765.5 3779.8 3816.5 5252.9 5281.4 5284.4 5297.9 5302.9 4593.0 4805.6 4860.4 4893.7 4895.0 5610.3 5622.7 5624.7 5630.4 5657.9 4095.5 4098.7 4129.9 4133.7 4156.4 5744.4 5804.7 5844.7 5903.3 5927.3 4221.3 4226.3 4236.7 4252.5 4267.1 4249.8 4276.8 4285.5 4286.8 4308.0 4711.4 4796.4 4860.2 4888.0 4889.9 5622.2 5630.6 5637.7 5653.0 5660.7 1424.8 1441.7 1443.9 1445.1 1446.2 959.8 985.7 994.6 1004.3 1011.6 5247.1 5330.6 5377.7 5399.8 5471.4 5754.3 5756.8 5780.9 5803.6 5805.2 5318.0 5542.8 5552.5 5554.1 5577.9 2934.7 2942.1 2955.8 2966.0 2978.4 5842.8 5849.3 5849.8 5854.6 5882.8 5496.3 5689.0 5703.3 5722.5 5784.5 5760.5 5827.6 5843.5 5849.8 5864.4 5298.2 5555.8 5568.4 5593.4 5659.6 5430.6 5473.4 5550.6 5563.9 5576.6 5765.4 5794.9 5798.9 5808.8 5819.0 4204.7 4229.0 4296.4 4309.0 4378.2 5511.5 5533.4 5570.4 5616.9 5657.4 5401.4 5405.1 5488.4 5513.3 5517.8 5308.6 5373.5 5387.5 5407.1 5487.7 5354.8 5376.2 5440.2 5450.0 5490.3 5411.6 5445.2 5538.9 5548.6 5669.0 5572.0 5578.3 5606.8 5615.0 5618.0 04:48:04 INFO - PROCESS | 32747 | 04:48:04 INFO - PROCESS | 32747 | [#5] yui.html Cycles:180 Average:18842.75 Median:25240.96 stddev:23224.94 (92.0%) stddev-sans-first:23250.65 04:48:04 INFO - PROCESS | 32747 | Values: 818.5 818.9 821.3 832.7 836.4 16324.3 17452.8 17897.3 18114.3 18187.7 16436.2 16497.6 17353.3 17458.7 18569.4 716.2 814.3 830.9 831.7 831.7 737.2 746.6 747.1 818.8 840.0 863.9 869.9 870.0 870.2 870.4 865.2 865.6 866.0 866.4 866.7 538.7 554.5 630.9 635.0 645.4 17227.6 17416.9 17506.4 17569.9 17621.6 14546.6 14851.0 15104.7 15184.8 15239.8 14138.9 14227.9 14401.2 14435.1 14510.9 4624.1 4673.8 4690.4 4702.4 4771.9 81073.0 81793.6 82160.6 82209.0 83839.2 11703.1 11726.8 11743.3 11790.8 12077.9 22524.5 22665.2 22685.7 23350.4 23402.8 8481.2 8687.9 8894.6 9014.6 9132.3 4546.4 4674.7 4696.6 4766.2 4787.0 3844.2 3951.1 3951.3 4051.0 4228.0 715.4 722.4 725.0 725.3 742.2 819.8 844.8 851.9 861.5 864.3 10879.4 11310.7 11566.1 11569.0 11901.1 849.2 854.6 858.2 859.6 867.0 5287.4 5313.6 5418.6 5424.8 5457.6 82858.0 83599.0 84120.0 85118.6 86627.6 56979.0 72402.0 72907.0 73045.6 73407.6 62568.7 62610.4 63926.4 64907.4 66644.3 10181.9 10441.2 10460.3 10489.5 10496.5 44545.6 44905.0 44905.8 45343.3 45857.8 54983.5 55487.0 56258.4 57496.1 57526.0 14128.2 14231.6 14375.4 14977.6 15120.9 15578.8 15715.2 16033.3 16492.5 16592.9 15297.4 15769.0 16180.3 16366.0 16905.7 15227.8 16059.8 16439.4 16516.0 16716.2 14369.1 15309.9 15355.2 15453.8 15883.9 11348.9 11832.0 12112.4 12460.4 12745.0 12409.6 12889.7 12974.1 13207.3 13318.6 04:48:04 INFO - PROCESS | 32747 | -------- Summary: end -------- 04:48:04 INFO - PROCESS | 32747 | 04:48:05 INFO - TEST-INFO | 32747: exit 0 04:48:05 INFO - TEST-OK | dromaeo_css | took 1114414ms 04:48:05 INFO - TEST-START | kraken 04:48:05 INFO - operating with platform_type : linux_ 04:48:05 INFO - Initialising browser for kraken test... 04:48:05 INFO - TEST-INFO | started process 602 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp53CCoo/profile http://localhost:40830/getInfo.html) 04:48:09 INFO - PROCESS | 602 | __metrics Screen width/height:1600/1200 04:48:09 INFO - PROCESS | 602 | colorDepth:24 04:48:09 INFO - PROCESS | 602 | Browser inner width/height: 1024/697 04:48:09 INFO - PROCESS | 602 | __metrics 04:48:09 INFO - PROCESS | 602 | 1486644489818 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:48:09 INFO - PROCESS | 602 | 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" 04:48:09 INFO - PROCESS | 602 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:10 INFO - PROCESS | 602 | console.error: PushService: 04:48:10 INFO - PROCESS | 602 | stateChangeProcessEnqueue: Error transitioning state 04:48:10 INFO - PROCESS | 602 | UnknownError 04:48:10 INFO - TEST-INFO | 602: exit 0 04:48:10 INFO - Browser initialized. 04:48:10 INFO - Running cycle 1/1 for kraken test... 04:48:10 INFO - TEST-INFO | started process 695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp53CCoo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:22 INFO - PROCESS | 695 | RSS: Main: 138346496 04:48:22 INFO - PROCESS | 695 | 04:48:25 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40830/tests/kraken/driver.html?testName=audio-beat-detection) 04:48:26 INFO - PROCESS | 695 | RSS: Main: 181919744 04:48:26 INFO - PROCESS | 695 | 04:48:28 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40830/tests/kraken/driver.html?testName=audio-dft) 04:48:29 INFO - PROCESS | 695 | RSS: Main: 171741184 04:48:29 INFO - PROCESS | 695 | 04:48:31 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40830/tests/kraken/driver.html?testName=audio-fft) 04:48:32 INFO - PROCESS | 695 | RSS: Main: 263507968 04:48:32 INFO - PROCESS | 695 | 04:48:34 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40830/tests/kraken/driver.html?testName=audio-oscillator) 04:48:35 INFO - PROCESS | 695 | RSS: Main: 169238528 04:48:35 INFO - PROCESS | 695 | 04:48:37 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40830/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:48:37 INFO - PROCESS | 695 | RSS: Main: 171929600 04:48:37 INFO - PROCESS | 695 | 04:48:40 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40830/tests/kraken/driver.html?testName=imaging-darkroom) 04:48:41 INFO - PROCESS | 695 | RSS: Main: 211091456 04:48:41 INFO - PROCESS | 695 | 04:48:44 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40830/tests/kraken/driver.html?testName=imaging-desaturate) 04:48:45 INFO - PROCESS | 695 | RSS: Main: 210124800 04:48:45 INFO - PROCESS | 695 | 04:48:48 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40830/tests/kraken/driver.html?testName=json-parse-financial) 04:48:48 INFO - PROCESS | 695 | RSS: Main: 208310272 04:48:48 INFO - PROCESS | 695 | 04:48:50 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40830/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:48:50 INFO - PROCESS | 695 | RSS: Main: 170553344 04:48:50 INFO - PROCESS | 695 | 04:48:52 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:48:53 INFO - PROCESS | 695 | RSS: Main: 177340416 04:48:53 INFO - PROCESS | 695 | 04:48:55 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:48:55 INFO - PROCESS | 695 | RSS: Main: 187166720 04:48:55 INFO - PROCESS | 695 | 04:48:58 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:48:58 INFO - PROCESS | 695 | RSS: Main: 196825088 04:48:58 INFO - PROCESS | 695 | 04:49:01 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:49:02 INFO - PROCESS | 695 | RSS: Main: 192864256 04:49:02 INFO - PROCESS | 695 | 04:49:03 INFO - PROCESS | 695 | Cycle 1(1): loaded http://localhost:40830/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40830/tests/kraken/driver.html?testName=ai-astar) 04:49:04 INFO - PROCESS | 695 | RSS: Main: 244424704 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | __start_tp_report 04:49:04 INFO - PROCESS | 695 | _x_x_mozilla_page_load 04:49:04 INFO - PROCESS | 695 | _x_x_mozilla_page_load_details 04:49:04 INFO - PROCESS | 695 | |i|pagename|runs| 04:49:04 INFO - PROCESS | 695 | |0;ai-astar;110;112;113;112;111;111;120;111;111;110 04:49:04 INFO - PROCESS | 695 | |1;audio-beat-detection;143;123;122;152;116;118;117;116;134;115 04:49:04 INFO - PROCESS | 695 | |2;audio-dft;161;161;162;162;160;161;162;161;161;159 04:49:04 INFO - PROCESS | 695 | |3;audio-fft;75;75;80;114;75;76;77;77;89;77 04:49:04 INFO - PROCESS | 695 | |4;audio-oscillator;82;82;104;82;83;83;93;82;82;104 04:49:04 INFO - PROCESS | 695 | |5;imaging-gaussian-blur;117;118;117;116;116;116;117;117;117;118 04:49:04 INFO - PROCESS | 695 | |6;imaging-darkroom;146;145;146;146;146;146;147;147;146;147 04:49:04 INFO - PROCESS | 695 | |7;imaging-desaturate;96;95;97;96;96;95;96;96;96;95 04:49:04 INFO - PROCESS | 695 | |8;json-parse-financial;52;50;49;49;49;49;48;49;50;49 04:49:04 INFO - PROCESS | 695 | |9;json-stringify-tinderbox;63;63;64;65;63;63;64;63;64;64 04:49:04 INFO - PROCESS | 695 | |10;stanford-crypto-aes;75;75;76;84;84;77;75;75;82;79 04:49:04 INFO - PROCESS | 695 | |11;stanford-crypto-ccm;123;122;126;143;121;119;121;119;157;123 04:49:04 INFO - PROCESS | 695 | |12;stanford-crypto-pbkdf2;157;155;158;155;164;157;156;153;154;158 04:49:04 INFO - PROCESS | 695 | |13;stanford-crypto-sha256-iterative;62;60;60;60;60;59;66;60;61;58 04:49:04 INFO - PROCESS | 695 | __end_tp_report 04:49:04 INFO - PROCESS | 695 | __start_cc_report 04:49:04 INFO - PROCESS | 695 | _x_x_mozilla_cycle_collect,1508 04:49:04 INFO - PROCESS | 695 | __end_cc_report 04:49:04 INFO - PROCESS | 695 | __startTimestamp1486644544137__endTimestamp 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | ------- Summary: start ------- 04:49:04 INFO - PROCESS | 695 | Number of tests: 14 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#0] ai-astar Cycles:10 Average:112.10 Median:111.50 stddev:2.92 (2.6%) stddev-sans-first:3.00 04:49:04 INFO - PROCESS | 695 | Values: 110.0 112.0 113.0 112.0 111.0 111.0 120.0 111.0 111.0 110.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:122.50 stddev:12.99 (10.6%) stddev-sans-first:12.16 04:49:04 INFO - PROCESS | 695 | Values: 143.0 123.0 122.0 152.0 116.0 118.0 117.0 116.0 134.0 115.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 04:49:04 INFO - PROCESS | 695 | Values: 161.0 161.0 162.0 162.0 160.0 161.0 162.0 161.0 161.0 159.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#3] audio-fft Cycles:10 Average:81.50 Median:77.00 stddev:12.17 (15.8%) stddev-sans-first:12.68 04:49:04 INFO - PROCESS | 695 | Values: 75.0 75.0 80.0 114.0 75.0 76.0 77.0 77.0 89.0 77.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:82.00 stddev:9.23 (11.3%) stddev-sans-first:9.55 04:49:04 INFO - PROCESS | 695 | Values: 82.0 82.0 104.0 82.0 83.0 83.0 93.0 82.0 82.0 104.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 04:49:04 INFO - PROCESS | 695 | Values: 117.0 118.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 118.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 04:49:04 INFO - PROCESS | 695 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 147.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 04:49:04 INFO - PROCESS | 695 | Values: 96.0 95.0 97.0 96.0 96.0 95.0 96.0 96.0 96.0 95.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.00 stddev:1.07 (2.2%) stddev-sans-first:0.60 04:49:04 INFO - PROCESS | 695 | Values: 52.0 50.0 49.0 49.0 49.0 49.0 48.0 49.0 50.0 49.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 04:49:04 INFO - PROCESS | 695 | Values: 63.0 63.0 64.0 65.0 63.0 63.0 64.0 63.0 64.0 64.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.00 stddev:3.79 (4.9%) stddev-sans-first:3.84 04:49:04 INFO - PROCESS | 695 | Values: 75.0 75.0 76.0 84.0 84.0 77.0 75.0 75.0 82.0 79.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:123.00 stddev:12.53 (10.2%) stddev-sans-first:13.19 04:49:04 INFO - PROCESS | 695 | Values: 123.0 122.0 126.0 143.0 121.0 119.0 121.0 119.0 157.0 123.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:157.00 stddev:3.06 (1.9%) stddev-sans-first:3.24 04:49:04 INFO - PROCESS | 695 | Values: 157.0 155.0 158.0 155.0 164.0 157.0 156.0 153.0 154.0 158.0 04:49:04 INFO - PROCESS | 695 | 04:49:04 INFO - PROCESS | 695 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:60.00 stddev:2.17 (3.6%) stddev-sans-first:2.24 04:49:04 INFO - PROCESS | 695 | Values: 62.0 60.0 60.0 60.0 60.0 59.0 66.0 60.0 61.0 58.0 04:49:04 INFO - PROCESS | 695 | -------- Summary: end -------- 04:49:04 INFO - PROCESS | 695 | 04:49:05 INFO - TEST-INFO | 695: exit 0 04:49:05 INFO - TEST-OK | kraken | took 60094ms 04:49:05 INFO - SUITE-END | took 1174s 04:49:05 INFO - Completed test suite (00:19:34) 04:49:05 INFO - javascript benchmark 04:49:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.4878043903195, 3347.5313456116146, 3353.4131526982774, 3380.6281309056017, 3392.7964322140674, 5495.175723641456, 5536.501714845673, 5544.251526043983, 5558.277423934883, 5563.610547261688, 5471.781128754855, 5578.470045345685, 5594.636348637336, 5610.354809196943, 5622.437756224375, 5070.112730272206, 5093.821716239932, 5111.131107711698, 5163.406208286039, 5171.508706672879, 4879.341288926003, 5095.643304968635, 5130.025295193901, 5152.124138896378, 5153.922691159617, 4869.099216644914, 4973.602111831063, 4985.975070124644, 5017.749112544377, 5085.898282034357, 5068.3917929848285, 5109.0, 5110.948890511085, 5118.616103792212, 5145.9485405146215, 4955.628327875388, 5020.497950204986, 5072.72100034498, 5085.1355269604255, 5089.312942752718, 5359.490848369438, 5403.864903377408, 5412.512873841334, 5466.426025267329, 5473.425290344536, 5874.441928016831, 5890.97054514729, 5919.052951527735, 5922.763089476416, 5951.791687290967, 5951.137085122633, 5961.672108034057, 5964.761409543613, 5973.044312909957, 5978.55160862937, 5425.701586412746, 5503.669779813224, 5525.254090697785, 5533.059379905426, 5570.220169176318, 6006.969965150233, 6060.636361818305, 6064.0, 6080.179175811209, 6101.908471372933, 5809.186713860063, 5853.882922341614, 5863.237779088777, 5866.941330586639, 5868.23712917318, 5670.5747068969995, 5693.089105743061, 5746.913796293059, 5938.317093534299, 5939.910901336483, 5750.0, 5799.652020878761, 5824.155497452845, 5828.6502809831545, 5866.560007999417, 5811.709414529341, 5813.7383817728305, 5814.825555233349, 5819.53443724501, 5829.883402332014, 4433.933490997638, 4480.798364073625, 4494.775261236925, 4502.099580083996, 4514.0, 3999.3601023836045, 4020.2361551305157, 4031.5363733170475, 4079.796010199478, 4104.15864747726, 5669.922714684197, 5673.3475650299915, 5675.602707810413, 5676.4323567642905, 5677.205191273225, 5694.629849059798, 5795.507381872504, 5801.593888427769, 5930.9406905931255, 5933.940660593339, 5573.219749235111, 5636.549076073904, 5641.182028605829, 5649.406812284733, 5673.687947162945, 4976.527229913152, 4982.825601103944, 4988.226824842155, 4988.625853060962, 5024.070546948826, 5910.911336330045, 6060.030395136757, 6109.877802443926, 6114.633122012605, 6132.969335153296, 5714.257146570919, 5876.647401155944, 5896.941030589639, 5928.49607783326, 6031.0, 6201.875962480815, 6202.596831205957, 6203.565750397429, 6220.968895155495, 6227.06594010902, 5833.79581714638, 5889.175515427758, 5891.970540147443, 5893.086571581412, 5929.584929054925, 5775.7400916957995, 5786.3056433228285, 5793.79721709755, 5806.0, 5823.4467725566, 5669.688167150846, 5702.344230413555, 5722.599418040697, 5732.914006289992, 5743.827685169452, 5707.514861236676, 5713.028785106459, 5717.285339332583, 5718.971405143114, 5719.5138413235345, 5577.6653400796085, 5645.294338207724, 5677.630953987978, 5708.172315014383, 5712.257406537124, 5836.620619659709, 5844.853878653068, 5846.707664616837, 5852.736626851717, 5889.411058894077, 5704.258446401941, 5706.372299047019, 5708.942910571007, 5709.857253568694, 5714.828555143353], "value": 5451.01180571733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.6299206652603, 578.3378032153184, 610.5695484683297, 612.4824523277831, 652.057776512939, 35925.0, 47292.34476793313, 48809.267860982116, 48864.0, 49010.75494622531, 51355.22967155496, 51736.223956640584, 51738.22392664113, 51831.74084129574, 51939.0, 8687.218150366465, 8830.558472076402, 9460.432374057562, 9649.179819715333, 9657.32398732089, 10735.892641073606, 10904.61833835816, 10911.618093366733, 11327.093832493421, 11339.206255562114, 11431.5427382905, 11504.137189710764, 11517.654470365902, 11518.481668324945, 11522.135839812006, 11370.886291137107, 11400.543978240861, 11403.087752979825, 11418.54325826966, 11429.542818287258, 11288.774224515464, 11361.375124368156, 11367.829482557769, 11376.943115284454, 11385.0, 47651.5234847654, 48021.279680804815, 48033.51966480325, 48042.51957480415, 48046.5195348049, 4288.270993931039, 4485.551444855521, 4519.977400113011, 4522.253828118356, 4722.7402492862875, 32131.678683213337, 32217.355652887047, 32528.512072318936, 32742.0, 33708.49437258443, 4581.266997280452, 4635.142498637762, 4636.605888499482, 4651.162790697673, 4680.976595117037, 344332.0, 346003.0, 346492.1403143871, 346657.0, 350295.0, 63606.68196658987, 64275.0, 64288.35711642917, 64496.0, 64591.354086459476, 65901.0, 66069.33930660631, 66297.0, 66400.0, 66660.66669666718, 18440.0, 19095.427137185907, 19102.522436939187, 19108.33120840764, 19111.617767644846, 12975.740485190432, 13240.53658121961, 13369.799453008212, 13548.932255338661, 13987.300634968415, 19429.22283108688, 19441.319553815832, 19445.902770486056, 19505.707414388795, 19556.80443195578, 7153.101559650003, 7265.420775624682, 7337.706491740378, 7375.778726638209, 7423.44324175689, 926.6710317837205, 935.6818681648136, 936.9344145909721, 947.4126041854095, 951.471933077135, 31779.205519861727, 32526.34947301088, 32699.346013079605, 33270.83364583162, 35070.27563943382, 1070.2829104500004, 1073.044989958937, 1089.7330154112135, 1092.257265059751, 1107.4739498738036, 16190.514244615786, 16608.750868736977, 16929.15354232308, 16949.661006779796, 16982.660346792996, 580460.0, 585175.0, 585451.0, 585522.0, 585547.0, 61588.38411615916, 62021.0, 62125.37874621196, 62169.9999999991, 62214.0, 1279.7120647854158, 1408.739383214088, 1421.6943357178257, 1447.3920953199681, 1448.2613866927943, 29776.404471910875, 30810.0, 31223.0, 31567.684323156474, 31584.526232106997, 54369.0, 55686.0, 55818.0, 55826.0, 55837.0, 370512.0, 371646.0, 373192.0, 373406.06375744665, 374084.0, 29707.257318567208, 30483.7806487738, 30636.0, 30664.080077597708, 30688.386232275676, 44383.55616443924, 47219.0, 47751.28373074476, 48753.75623121862, 49036.0, 39915.2016959665, 40047.79976100218, 40570.391444127774, 41138.79430602828, 42023.0, 39821.60178398295, 39954.200915982095, 40924.386134207416, 41041.384379233736, 41379.1724165521, 39725.0, 39756.403653945796, 39792.0, 39862.20275594414, 40095.799521002205, 33261.50107748434, 35427.82286088553, 35686.64313356833, 35745.67653102644, 35989.82005090063, 27143.59284610693, 27406.5889011669, 27566.0, 27614.65275451609, 27855.72144278612], "value": 21286.80810250089, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.41405478353573, 438.776224125696, 443.2553310438463, 450.26157102352136, 457.38938517079697, 19881.900590497044, 21432.678509822366, 21479.463013424633, 21599.0, 21653.45866353342, 21995.23016694416, 22295.108195672194, 22397.888010559924, 22465.0, 22570.887145564247, 5890.175375447441, 5898.587098903068, 5992.7902523411685, 6063.333033366326, 6108.664023478706, 4529.479109902354, 4782.7608619569055, 5785.479306862381, 5857.707114644272, 5866.88266234675, 4753.382060332169, 4758.072175925696, 6470.967645161768, 6694.163229596301, 6730.629815360153, 6603.580230250495, 6660.267370589231, 6675.065490831287, 6693.598384096946, 6851.04085428038, 5899.893610113555, 5937.7921772737745, 5942.197803296543, 5951.077582974646, 5953.315368732608, 26443.867780661167, 26454.206373808818, 26479.205623831316, 26516.867415662797, 26671.73328266712, 3263.9347213055844, 3299.686529779691, 3327.8502467389026, 3386.915327116817, 3404.114930118162, 16166.191690415431, 16300.83699163005, 16307.592310192222, 16314.184290785532, 16317.673646527122, 2011.416689160142, 2043.6832290994919, 2052.0252879882087, 2087.405089549486, 2140.165335519149, 97907.0, 99575.99999999927, 99975.0, 100577.4971125147, 101447.0, 10595.940405959342, 11074.5570177192, 11298.887011129947, 11308.943455282671, 11709.88290117105, 15549.0, 16305.429309974093, 16334.264958076677, 17036.14819259032, 17125.828741712674, 8826.55867206637, 8933.776655583662, 8939.508327041945, 9071.228945539702, 9071.863922041175, 8567.785805354915, 8611.440256383315, 8613.913860861436, 8747.737567872973, 8857.512836793909, 7486.326230639269, 7615.086189657277, 7652.273034061755, 7733.883991740128, 7746.109197442253, 2095.847283993797, 2141.346889198809, 2152.967705484419, 2180.160638154322, 2184.224600266914, 894.5750768385095, 900.6262401103469, 906.5467266366817, 915.8168366326761, 931.3247895276006, 21097.156113755278, 21362.0, 21676.24133155332, 21778.455538611346, 21781.23765668194, 903.5301643145527, 903.6340282185726, 918.7932715139041, 927.6335847340263, 939.6992962252014, 11518.424078796026, 12054.835344956113, 12185.634430967086, 12364.1963272387, 12387.380630968415, 492219.0, 494888.0, 495152.0, 730369.0, 805208.0, 110765.0, 119533.0, 120585.0, 121861.0, 124361.0, 1106.2145876427826, 1142.0806250967885, 1167.9416029198505, 1171.9589814356457, 1174.0607513988775, 19127.713084304025, 19358.806411936264, 19378.127984240462, 19451.902740486774, 19540.511487212934, 44462.77768611136, 44628.77685611681, 45299.320510193036, 45373.54626453825, 46158.30762538632, 60909.69545152246, 64437.67781161064, 65409.34590654032, 66783.3321666777, 67004.3299566998, 8804.0, 8977.057408971963, 9568.234541236545, 9595.568199430903, 9918.702438926844, 11911.940440297743, 11952.282863028246, 12431.62705118848, 13175.14361566495, 13466.797998030233, 10956.561737530728, 11563.595274165364, 11728.354940478355, 11928.4035798208, 12387.442565084413, 11500.25248358854, 11547.480363383836, 11810.586629467927, 12068.396580171131, 12308.138430310151, 11486.19596628228, 11751.118666100248, 11865.940670296593, 11951.163418560966, 12252.387380631111, 7651.464397492122, 8848.380613357262, 9019.549022548978, 9026.45841249527, 9523.333366664267, 11816.113791465847, 12308.938455307667, 12315.0, 12389.814152787896, 12635.810462842881], "value": 10241.492418654418, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.229758971719, 1527.0608575725928, 1528.262386601536, 1548.4812587783092, 1557.9688406231876, 8426.452280601765, 9006.054364291753, 9011.684591039315, 9029.187373136416, 9032.68385606504, 7715.074191097076, 7921.603919804015, 7924.445288829785, 8152.225538573841, 8807.559622018905, 2389.7371289158177, 2461.692288463942, 2465.506898620283, 2488.0, 2489.601663733803, 2437.695288088989, 2464.2484042367073, 2467.938301542458, 2475.88858501367, 2487.614419764939, 2416.359664688859, 2420.9757902420924, 2438.670779444779, 2441.5361081394567, 2442.5481285962064, 2419.9153029643967, 2426.5268272686835, 2428.429319110008, 2429.927102186937, 2441.8046556275544, 2393.114547617393, 2410.252821625284, 2449.3876530867283, 2450.8529488230765, 2459.8770061496853, 9134.223590994707, 9185.17333439987, 9185.954070229673, 9189.954050229773, 9200.987891331948, 5410.891782164374, 5559.666420014772, 5562.3881373048935, 5568.109102543614, 5569.10894256917, 3268.3953468608147, 3416.333814906094, 3435.175557866119, 3475.982620086909, 3513.1919658478437, 1335.44578999715, 1435.8277006759151, 2193.517426166241, 2254.109626697462, 2259.830512711537, 12519.3740312984, 12592.874071259354, 12600.180988235736, 12633.178843375152, 12650.367481625974, 4552.7495987720995, 4752.382190315306, 4799.0, 4802.711837289704, 4859.1496488114435, 10020.198384129251, 10040.0, 10052.64815731446, 10068.949655251823, 10075.395476271447, 5542.057850165482, 5546.500814926676, 5549.750261238254, 5560.666360018412, 5566.972165139229, 4507.932381014288, 4997.225430058346, 5089.694618322913, 5120.078385890501, 5201.375834899761, 3703.851845926187, 3950.111224974358, 3995.6603688686787, 4048.5344185418467, 4058.0, 1144.3934714601276, 1146.3694967767694, 1151.8329842172839, 1158.8899054589801, 1162.976740465186, 992.1715367667933, 1000.1099021870541, 1016.2428990402198, 1017.5115944346755, 1018.4449475036123, 6060.424259695322, 6220.564560480813, 6257.624542527463, 6304.464120549805, 6485.805425837232, 1092.5083712329324, 1112.6050252160528, 1135.5855112883737, 1147.3345459633392, 1152.3604399558326, 4210.452641156691, 4220.746755194698, 4293.098449325614, 4310.137972405531, 4327.307630779061, 10765.192610554082, 10932.836007459895, 11113.388763618079, 11119.833202501968, 11137.832932506019, 10601.628942987112, 10817.83773243402, 10844.240903136862, 10857.239993200401, 10890.455477226107, 8325.791855203668, 8521.488710677379, 8550.74347769568, 8578.871316930252, 8608.91391086081, 5856.707164641836, 5872.207252021093, 5988.790392336247, 5994.6403215807195, 6026.789062382795, 10020.899791001997, 10042.146417554297, 10074.14369778577, 10112.140468060297, 10173.491325433552, 9707.611695532381, 10030.448325342177, 10088.949555252177, 10274.434906080238, 10368.53341599645, 7326.3040011198855, 7984.960075199587, 8133.308668762985, 8138.3489320853005, 8139.4709343892455, 7788.532688038736, 8577.228049475578, 8643.56782160902, 8883.511406872683, 8900.42147260426, 7870.212978702084, 7938.960305198437, 7939.722109726132, 7972.920270797218, 8085.151059138706, 7557.130929943127, 7850.371970242481, 7862.882056769267, 7919.366450684047, 7997.8400431992195, 6887.793366199022, 6912.066870972354, 6945.201301850352, 6986.126734158231, 7009.7897063088185, 5369.3556773187465, 5433.239346491416, 5448.673079615236, 5461.754221059982, 5496.642718223303, 5292.5501332387175, 5339.839804805862, 5387.380451248, 5409.0, 5440.374356949002], "value": 4820.590547334717, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.3610515153758, 417.92059508693336, 419.06497983930876, 419.25582091787095, 432.4810227726729, 5419.810306639269, 5423.674579525231, 5468.644538105031, 5478.260434841305, 5481.095619222829, 5124.360954108637, 5334.653247538917, 5370.731463426832, 5386.660270632204, 5390.676559406428, 4045.615666511677, 4104.199681062194, 4171.749695018309, 4227.408162857202, 4248.53266140725, 4219.818450833772, 4225.915481690365, 4231.1749208904275, 4233.83064677414, 4233.978830105845, 4200.1599680063955, 4207.347861081537, 4209.568746626146, 4212.4945006599255, 4219.346001369778, 3962.346212874872, 4015.3374693175883, 4166.250074986486, 4206.810693518783, 4209.221294060609, 3759.4360845873066, 3760.9845341757846, 3765.5104836371097, 3779.848806047755, 3816.46569480273, 5252.8686782830355, 5281.419043905167, 5284.392294886099, 5297.940411917593, 5302.893942121136, 4592.954070459286, 4805.567498925114, 4860.368152140234, 4893.657443978923, 4894.972055868272, 5610.270664813589, 5622.718864056781, 5624.734434752189, 5630.408807075239, 5657.868426314712, 4095.467589213413, 4098.72520750983, 4129.8967525811795, 4133.731307465006, 4156.397322388267, 5744.368119506851, 5804.7097645118, 5844.697745773667, 5903.321118071433, 5927.318358388798, 4221.261279276115, 4226.344916537942, 4236.661067114624, 4252.48970123581, 4267.125239325993, 4249.787510624456, 4276.764777937244, 4285.46431696038, 4286.77087914289, 4307.956920430755, 4711.434627844612, 4796.448408433005, 4860.198067318924, 4887.975560122177, 4889.877753056202, 5622.156676498558, 5630.577706672016, 5637.689927054052, 5652.982463156589, 5660.688662123623, 1424.8005279260913, 1441.7116576684705, 1443.935022923971, 1445.1329202478428, 1446.2407236200868, 959.7936443664506, 985.6791898855568, 994.557421947241, 1004.3271008424373, 1011.585250047477, 5247.134222853263, 5330.626856120112, 5377.704226267593, 5399.83800485986, 5471.37079235885, 5754.251947246831, 5756.848630273962, 5780.872729817729, 5803.593748437569, 5805.158252053428, 5318.0, 5542.778288868481, 5552.472515111058, 5554.1113421853115, 5577.944220557823, 2934.662513810939, 2942.146777434563, 2955.7783166262616, 2965.9534920904507, 2978.44898693738, 5842.766289348378, 5849.268841394825, 5849.795257165979, 5854.648721076749, 5882.764619429968, 5496.31296087989, 5688.971555142198, 5703.289013295995, 5722.456366645162, 5784.50831679312, 5760.51035661965, 5827.639805878626, 5843.474087332161, 5849.766009359748, 5864.413558644102, 5298.2052692096495, 5555.805546805843, 5568.359638641608, 5593.412691667317, 5659.603827732019, 5430.565554755531, 5473.397926228191, 5550.639208451437, 5563.888722225452, 5576.609637325349, 5765.365809760839, 5794.855128621818, 5798.94201057984, 5808.82573522795, 5818.970905145447, 4204.675527208919, 4229.043223896734, 4296.4414626098405, 4309.0, 4378.211921853969, 5511.5394420477805, 5533.418991005887, 5570.44295570456, 5616.85957851057, 5657.377688454185, 5401.351837779492, 5405.081136206775, 5488.423715509974, 5513.283273174462, 5517.806876759294, 5308.628396012242, 5373.543248823894, 5387.488188622075, 5407.107827208467, 5487.6981766003255, 5354.78580856777, 5376.247325374529, 5440.211169380497, 5450.0, 5490.286262785812, 5411.6211865170635, 5445.1832225166545, 5538.9169162461785, 5548.611597188158, 5668.971655141698, 5571.9721401392735, 5578.2748242728185, 5606.831795046155, 5615.0, 5618.0], "value": 4310.962374259522, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.5048045932215, 818.9385796065296, 821.3059964330141, 832.6877420967137, 836.4395854777299, 16324.265408056637, 17452.825471745295, 17897.28410863567, 18114.275428982826, 18187.727184092248, 16436.17819109043, 16497.587560311, 17353.305867765273, 17458.738118928228, 18569.442916712487, 716.2443621978811, 814.2752949874616, 830.8919840420767, 831.6507067031831, 831.7213733399318, 737.1670012885432, 746.5781833264189, 747.0661672908864, 818.8485130250892, 840.0255703384075, 863.9006514250848, 869.9347548933823, 870.0212261206143, 870.2385412763832, 870.4385671242045, 865.1824026295153, 865.6364326982682, 866.0473479172891, 866.445474896067, 866.7183165471247, 538.7010209333819, 554.5425024354882, 630.914826498426, 634.9968250158765, 645.441692935612, 17227.569310767205, 17416.912915435467, 17506.387276445264, 17569.912150439293, 17621.55946101345, 14546.636334091627, 14851.0, 15104.69516187892, 15184.772228416583, 15239.771403428957, 14138.929305353511, 14227.85772142286, 14401.20793356365, 14435.133891966516, 14510.854891451161, 4624.144533261324, 4673.813047478096, 4690.390249267608, 4702.435707715096, 4771.928421073687, 81073.0, 81793.59103204445, 82160.58919705363, 82209.0, 83839.16160838435, 11703.063754899587, 11726.765464690829, 11743.295402275891, 11790.764184716429, 12077.879221207852, 22524.54950900973, 22665.2067177648, 22685.659715104288, 23350.416239594146, 23402.765972340057, 8481.194286542892, 8687.869681954775, 8894.555272236363, 9014.63941442348, 9132.269418446642, 4546.431696037996, 4674.672772905932, 4696.553827386393, 4766.237402015661, 4786.976065119652, 3844.1927195288745, 3951.05174758056, 3951.308521008812, 4050.9594904051173, 4228.04868904494, 715.4062128433388, 722.3607107709714, 725.0320821703085, 725.2783480436893, 742.1873049011324, 819.7950512371907, 844.7845799321134, 851.9275861551838, 861.4615865084322, 864.3430992445661, 10879.401632910265, 11310.717232069099, 11566.132540059529, 11569.0, 11901.107416943763, 849.1720572441943, 854.5855260198798, 858.1847245117164, 859.6002858670623, 866.9913300867037, 5287.365516138011, 5313.57491400687, 5418.593605479605, 5424.83725488236, 5457.617966742369, 82858.0, 83599.0, 84120.0, 85118.57440712757, 86627.56686216529, 56979.0, 72402.0, 72907.0, 73045.6347718258, 73407.63296183485, 62568.68715656484, 62610.373896261364, 63926.36073639297, 64907.350926491075, 66644.33355666479, 10181.898181018096, 10441.164706823283, 10460.267781255543, 10489.527971241465, 10496.47517624131, 44545.55454445414, 44905.0, 44905.77547112243, 45343.31985020293, 45857.77071114623, 54983.45016549783, 55487.0, 56258.43741562532, 57496.13755793583, 57526.0, 14128.152310861382, 14231.64420889486, 14375.353109109907, 14977.550673479813, 15120.924395377953, 15578.766318505004, 15715.214239288218, 16033.278502467183, 16492.505224843273, 16592.91703541523, 15297.388104476142, 15769.0, 16180.271887764844, 16366.0, 16905.74641380405, 15227.847721523087, 16059.839401606301, 16439.424620138238, 16516.0, 16716.24776885019, 14369.13785172893, 15309.923450382677, 15355.23223838826, 15453.768193477332, 15883.920580397024, 11348.943255283672, 11832.0, 12112.394380280775, 12460.439280232593, 12745.0, 12409.565665201675, 12889.742205156032, 12974.118752780983, 13207.27359995171, 13318.600441986757], "value": 7916.4601413153, "unit": "score"}], "name": "dromaeo_css", "value": 7618.458245115679, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 113.0, 112.0, 111.0, 111.0, 120.0, 111.0, 111.0, 110.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 123.0, 122.0, 152.0, 116.0, 118.0, 117.0, 116.0, 134.0, 115.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 160.0, 161.0, 162.0, 161.0, 161.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 80.0, 114.0, 75.0, 76.0, 77.0, 77.0, 89.0, 77.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 104.0, 82.0, 83.0, 83.0, 93.0, 82.0, 82.0, 104.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 49.0, 49.0, 49.0, 49.0, 48.0, 49.0, 50.0, 49.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 65.0, 63.0, 63.0, 64.0, 63.0, 64.0, 64.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 84.0, 84.0, 77.0, 75.0, 75.0, 82.0, 79.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 122.0, 126.0, 143.0, 121.0, 119.0, 121.0, 119.0, 157.0, 123.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 155.0, 158.0, 155.0, 164.0, 157.0, 156.0, 153.0, 154.0, 158.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 60.0, 60.0, 59.0, 66.0, 60.0, 61.0, 58.0], "value": 60.6, "unit": "score"}], "name": "kraken", "value": 1462.7, "alertThreshold": 2.0}]} 04:49:05 INFO - Return code: 0 04:49:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:49:05 ERROR - # TBPL SUCCESS # 04:49:05 INFO - Running post-action listener: _resource_record_post_action 04:49:05 INFO - [mozharness: 2017-02-09 12:49:05.456674Z] Finished run-tests step (success) 04:49:05 INFO - Running post-run listener: _resource_record_post_run 04:49:05 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 262144; Write bytes: 380506112; Read time: 20; Write time: 270112 04:49:05 INFO - TinderboxPrint: CPU usage
13.1% 04:49:05 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 20 04:49:05 INFO - TinderboxPrint: I/O write bytes / time
380,506,112 / 270,112 04:49:05 INFO - TinderboxPrint: CPU idle
8,263.9 (86.9%) 04:49:05 INFO - TinderboxPrint: CPU user
1,194.3 (12.6%) 04:49:05 INFO - TinderboxPrint: Swap in / out
0 / 0 04:49:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109998080; Read time: 0; Write time: 110416 04:49:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 253636608; Read time: 20; Write time: 152436 04:49:06 INFO - Running post-run listener: _upload_blobber_files 04:49:06 INFO - Blob upload gear active. 04:49:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:49:06 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 04:49:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:49:06 INFO - (blobuploader) - INFO - Open directory for files ... 04:49:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:49:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:07 INFO - (blobuploader) - INFO - Done attempting. 04:49:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:49:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:49:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:08 INFO - (blobuploader) - INFO - Done attempting. 04:49:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:49:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:49:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:11 INFO - (blobuploader) - INFO - Done attempting. 04:49:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:49:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:49:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:12 INFO - (blobuploader) - INFO - Done attempting. 04:49:12 INFO - (blobuploader) - INFO - Iteration through files over. 04:49:12 INFO - Return code: 0 04:49:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:49:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:49:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ef9c29c104a6f9ce30f82d9691b591af9ff36435d047825e5958a3ce085f39214fb29c7dd14048240e049a69f6ca4e6931bd02636ff8273a20e7261e565d2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51a48d4048fea80dcde24c0a9d66e04685e2d9e092f59fb858f9c6c764442bafe9b48233d25376ee046768bf7740aa7cabaa7001c786d1bce0e59c3d0790d9a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b248e6d75a1da38631e37dcaaaab97aecea483ac627fc1ad345063ba589e9a7c51f32a801412430506aef7a0493ddeef4e39ab790242b4212e7d77e294f716b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74c9bc3edc5ed19764d653ccc0342c1bfc96840289372e0cd2b108f8a003d846ac51a9f4d16dc7b10c2d56b16e9c385040eff164c1bff14e867fa75bc592d3b3"} 04:49:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:49:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:49:12 INFO - Contents: 04:49:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ef9c29c104a6f9ce30f82d9691b591af9ff36435d047825e5958a3ce085f39214fb29c7dd14048240e049a69f6ca4e6931bd02636ff8273a20e7261e565d2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51a48d4048fea80dcde24c0a9d66e04685e2d9e092f59fb858f9c6c764442bafe9b48233d25376ee046768bf7740aa7cabaa7001c786d1bce0e59c3d0790d9a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b248e6d75a1da38631e37dcaaaab97aecea483ac627fc1ad345063ba589e9a7c51f32a801412430506aef7a0493ddeef4e39ab790242b4212e7d77e294f716b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74c9bc3edc5ed19764d653ccc0342c1bfc96840289372e0cd2b108f8a003d846ac51a9f4d16dc7b10c2d56b16e9c385040eff164c1bff14e867fa75bc592d3b3"} 04:49:12 INFO - Running post-run listener: copy_logs_to_upload_dir 04:49:12 INFO - Copying logs to upload dir... 04:49:12 INFO - mkdir: /builds/slave/test/build/upload/logs 04:49:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.641649 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 04:49:12.072407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:12.075987) ========= 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=51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ef9c29c104a6f9ce30f82d9691b591af9ff36435d047825e5958a3ce085f39214fb29c7dd14048240e049a69f6ca4e6931bd02636ff8273a20e7261e565d2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51a48d4048fea80dcde24c0a9d66e04685e2d9e092f59fb858f9c6c764442bafe9b48233d25376ee046768bf7740aa7cabaa7001c786d1bce0e59c3d0790d9a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b248e6d75a1da38631e37dcaaaab97aecea483ac627fc1ad345063ba589e9a7c51f32a801412430506aef7a0493ddeef4e39ab790242b4212e7d77e294f716b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74c9bc3edc5ed19764d653ccc0342c1bfc96840289372e0cd2b108f8a003d846ac51a9f4d16dc7b10c2d56b16e9c385040eff164c1bff14e867fa75bc592d3b3"} build_url:https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/KsvT3t2_R0q6k6uy7P5YCw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ef9c29c104a6f9ce30f82d9691b591af9ff36435d047825e5958a3ce085f39214fb29c7dd14048240e049a69f6ca4e6931bd02636ff8273a20e7261e565d2a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51a48d4048fea80dcde24c0a9d66e04685e2d9e092f59fb858f9c6c764442bafe9b48233d25376ee046768bf7740aa7cabaa7001c786d1bce0e59c3d0790d9a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b248e6d75a1da38631e37dcaaaab97aecea483ac627fc1ad345063ba589e9a7c51f32a801412430506aef7a0493ddeef4e39ab790242b4212e7d77e294f716b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74c9bc3edc5ed19764d653ccc0342c1bfc96840289372e0cd2b108f8a003d846ac51a9f4d16dc7b10c2d56b16e9c385040eff164c1bff14e867fa75bc592d3b3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:12.100988) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:12.101327) ========= 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=51fc86400edbde9650d2781a000001d0-1486643171.916614-1977763734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004388 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:12.183563) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 04:49:12.183893) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 04:49:12.184193) ========= ========= Total master_lag: 0.16 =========