builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1479886942.26 results: success (0) buildid: 20161122222435 builduid: fdd1dec18f72483bb3d778ad471a6c0a revision: 2622d62d1f0803bfdb497af8e3405b02fd7e478f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.255547) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.256564) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.256848) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.311103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.311362) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-22 23:42:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-11-22 23:42:22 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122107 ========= master_lag: 0.46 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.888584) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.888872) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017048 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:22.964050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 23:42:22.964383) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 2622d62d1f0803bfdb497af8e3405b02fd7e478f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 2622d62d1f0803bfdb497af8e3405b02fd7e478f --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=56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-22 23:42:23,316 truncating revision to first 12 chars 2016-11-22 23:42:23,317 Setting DEBUG logging. 2016-11-22 23:42:23,317 attempt 1/10 2016-11-22 23:42:23,317 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/2622d62d1f08?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 23:42:24,452 unpacking tar archive at: graphics-2622d62d1f08/testing/mozharness/ program finished with exit code 0 elapsedTime=1.465005 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 23:42:24.461105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:24.461428) ========= script_repo_revision: 2622d62d1f0803bfdb497af8e3405b02fd7e478f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:24.461831) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:24.462104) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 23:42:24.474187) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-11-22 23:42:24.474491) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:42:24 INFO - MultiFileLogger online at 20161122 23:42:24 in /builds/slave/test 23:42:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 23:42:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:42:24 INFO - {'append_to_log': False, 23:42:24 INFO - 'base_work_dir': '/builds/slave/test', 23:42:24 INFO - 'blob_upload_branch': 'Graphics', 23:42:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:42:24 INFO - 'branch': 'Graphics', 23:42:24 INFO - 'buildbot_json_path': 'buildprops.json', 23:42:24 INFO - 'config_files': ('talos/linux_config.py',), 23:42:24 INFO - 'default_actions': ('clobber', 23:42:24 INFO - 'read-buildbot-config', 23:42:24 INFO - 'download-and-extract', 23:42:24 INFO - 'populate-webroot', 23:42:24 INFO - 'create-virtualenv', 23:42:24 INFO - 'install', 23:42:24 INFO - 'run-tests'), 23:42:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:42:24 INFO - 'download_minidump_stackwalk': True, 23:42:24 INFO - 'download_symbols': 'ondemand', 23:42:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:42:24 INFO - 'tooltool.py': '/tools/tooltool.py', 23:42:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:42:24 INFO - '/tools/misc-python/virtualenv.py')}, 23:42:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:42:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:42:24 INFO - 'installer_path': 'installer.exe', 23:42:24 INFO - 'log_level': 'info', 23:42:24 INFO - 'log_name': 'talos', 23:42:24 INFO - 'log_to_console': True, 23:42:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:42:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:42:24 INFO - 'opt_config_files': (), 23:42:24 INFO - 'pip_index': False, 23:42:24 INFO - 'sps_profile': False, 23:42:24 INFO - 'sps_profile_interval': 0, 23:42:24 INFO - 'suite': 'dromaeojs', 23:42:24 INFO - 'system_bits': '32', 23:42:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:42:24 INFO - 'title': 'talos-linux64-ix-037', 23:42:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:42:24 INFO - 'use_talos_json': True, 23:42:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:42:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:42:24 INFO - 'work_dir': 'build'} 23:42:24 INFO - [mozharness: 2016-11-23 07:42:24.915258Z] Running clobber step. 23:42:24 INFO - Running pre-action listener: _resource_record_pre_action 23:42:24 INFO - Running main action method: clobber 23:42:24 INFO - rmtree: /builds/slave/test/build 23:42:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:42:25 INFO - Running post-action listener: _resource_record_post_action 23:42:25 INFO - [mozharness: 2016-11-23 07:42:25.374270Z] Finished clobber step (success) 23:42:25 INFO - [mozharness: 2016-11-23 07:42:25.374369Z] Running read-buildbot-config step. 23:42:25 INFO - Running pre-action listener: _resource_record_pre_action 23:42:25 INFO - Running main action method: read_buildbot_config 23:42:25 INFO - Using buildbot properties: 23:42:25 INFO - { 23:42:25 INFO - "project": "", 23:42:25 INFO - "product": "firefox", 23:42:25 INFO - "script_repo_revision": "default", 23:42:25 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 23:42:25 INFO - "repository": "", 23:42:25 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 23:42:25 INFO - "buildid": "20161122222435", 23:42:25 INFO - "pgo_build": "False", 23:42:25 INFO - "basedir": "/builds/slave/test", 23:42:25 INFO - "buildnumber": 20, 23:42:25 INFO - "slavename": "talos-linux64-ix-037", 23:42:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:42:25 INFO - "platform": "ubuntu64_hw", 23:42:25 INFO - "scheduler": "tests-graphics-linux64-talos", 23:42:25 INFO - "branch": "graphics", 23:42:25 INFO - "repo_path": "projects/graphics", 23:42:25 INFO - "revision": "2622d62d1f0803bfdb497af8e3405b02fd7e478f", 23:42:25 INFO - "stage_platform": "linux64", 23:42:25 INFO - "builduid": "fdd1dec18f72483bb3d778ad471a6c0a", 23:42:25 INFO - "slavebuilddir": "test" 23:42:25 INFO - } 23:42:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 23:42:25 INFO - Running post-action listener: _resource_record_post_action 23:42:25 INFO - [mozharness: 2016-11-23 07:42:25.376769Z] Finished read-buildbot-config step (success) 23:42:25 INFO - [mozharness: 2016-11-23 07:42:25.376857Z] Running download-and-extract step. 23:42:25 INFO - Running pre-action listener: _resource_record_pre_action 23:42:25 INFO - Running main action method: download_and_extract 23:42:25 INFO - mkdir: /builds/slave/test/build/tests 23:42:25 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')]} 23:42:25 INFO - trying https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:42:25 INFO - Downloading https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:42:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:42:25 INFO - Downloaded 1361 bytes. 23:42:25 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:42:25 INFO - Using the following test package requirements: 23:42:25 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:42:25 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:42:25 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:42:25 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'jsshell-linux-x86_64.zip'], 23:42:25 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:42:25 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:42:25 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:42:25 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:42:25 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:42:25 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:25 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:42:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:42:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:42:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:42:25 INFO - Fetch https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:42:26 INFO - Http code: 200 23:42:26 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 23:42:26 INFO - x-amz-cf-id: HjpXYFNCiGNNMSXFOH7zz-C-Zweep9j72DgAb7S7uLTRgMnp6KXCow== 23:42:26 INFO - x-amz-version-id: Gm7w6zKDaG4ZNLVeXiYSynI4u.gkubya 23:42:26 INFO - Expected file size: 20183379 23:42:26 INFO - Obtained file size: 20183379 23:42:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:42:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:42:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:42:30 INFO - Fetch https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:42:31 INFO - Http code: 200 23:42:31 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 23:42:31 INFO - x-amz-cf-id: DEcP-8-KPNWgFdAxBhN2VnR9tVMtz0PDXJSQKZP-auo4Q07YtxFEJg== 23:42:31 INFO - x-amz-version-id: Gm7w6zKDaG4ZNLVeXiYSynI4u.gkubya 23:42:31 INFO - Expected file size: 20183379 23:42:31 INFO - Obtained file size: 20183379 23:42:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 23:42:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:42:35 INFO - Fetch https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:42:36 INFO - Http code: 200 23:42:36 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 23:42:36 INFO - x-amz-cf-id: HMTTbKKoHH1dNFc4nqhWQITHNu2XSh0LZnSQSWb8lzSlBUWseb3hDQ== 23:42:36 INFO - x-amz-version-id: vphxwWnlxH2J8Y6nI7BoZpGkMmP1Qkrq 23:42:37 INFO - Expected file size: 14409392 23:42:37 INFO - Obtained file size: 14409392 23:42:37 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')]} 23:42:37 INFO - trying https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:37 INFO - Downloading https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:42:39 INFO - Downloaded 55600748 bytes. 23:42:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:39 INFO - mkdir: /builds/slave/test/properties 23:42:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:42:39 INFO - Writing to file /builds/slave/test/properties/build_url 23:42:39 INFO - Contents: 23:42:39 INFO - build_url:https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:40 INFO - Running post-action listener: _resource_record_post_action 23:42:40 INFO - Running post-action listener: set_extra_try_arguments 23:42:40 INFO - [mozharness: 2016-11-23 07:42:40.922924Z] Finished download-and-extract step (success) 23:42:40 INFO - [mozharness: 2016-11-23 07:42:40.923150Z] Running populate-webroot step. 23:42:40 INFO - Running pre-action listener: _resource_record_pre_action 23:42:40 INFO - Running main action method: populate_webroot 23:42:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:42:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:42:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:42:40 INFO - '--tppagecycles', 23:42:40 INFO - '7'], 23:42:40 INFO - 'tests': ['tsvgm']}}, 23:42:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:42:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:42:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:42:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:42:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:42:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:42:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['damp', 'tps']}, 23:42:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'tests': ['damp', 'tps']}, 23:42:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['dromaeo_dom']}, 23:42:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:42:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['basic_compositor_video']}, 23:42:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:42:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other-e10s': {'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tsvgx', 23:42:40 INFO - 'tsvgr_opacity', 23:42:40 INFO - 'tart', 23:42:40 INFO - 'tscrollx', 23:42:40 INFO - 'cart']}, 23:42:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:42:40 INFO - 'tsvgr_opacity', 23:42:40 INFO - 'tart', 23:42:40 INFO - 'tscrollx', 23:42:40 INFO - 'cart']}, 23:42:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tp5o']}, 23:42:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:42:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s', 23:42:40 INFO - '--xperf_path', 23:42:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:42:40 INFO - 'tests': ['tp5n']}, 23:42:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:42:40 INFO - 'talos_options': ['--xperf_path', 23:42:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:42:40 INFO - 'tests': ['tp5n']}}, 23:42:40 INFO - 'talos.zip': {'path': '', 23:42:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:42:40 INFO - Running post-action listener: _resource_record_post_action 23:42:40 INFO - [mozharness: 2016-11-23 07:42:40.941449Z] Finished populate-webroot step (success) 23:42:40 INFO - [mozharness: 2016-11-23 07:42:40.941535Z] Running create-virtualenv step. 23:42:40 INFO - Running pre-action listener: _resource_record_pre_action 23:42:40 INFO - Running main action method: create_virtualenv 23:42:40 INFO - Creating virtualenv /builds/slave/test/build/venv 23:42:40 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 23:42:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:42:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:42:41 INFO - Using real prefix '/usr' 23:42:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:42:41 INFO - Installing distribute.............................................................................................................................................................................................done. 23:42:43 INFO - Installing pip.................done. 23:42:43 INFO - Return code: 0 23:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:42:43 INFO - Reading from file tmpfile_stdout 23:42:43 INFO - Output received: 23:42:43 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) 23:42:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:42:43 INFO - Using env: {'DISPLAY': ':0', 23:42:43 INFO - 'HOME': '/home/cltbld', 23:42:43 INFO - 'LANG': 'en_US.UTF-8', 23:42:43 INFO - 'LANGUAGE': 'en_US:en', 23:42:43 INFO - 'LOGNAME': 'cltbld', 23:42:43 INFO - 'MAIL': '/var/mail/cltbld', 23:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:43 INFO - 'MOZ_NO_REMOTE': '1', 23:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:43 INFO - 'NO_EM_RESTART': '1', 23:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:43 INFO - 'PWD': '/builds/slave/test', 23:42:43 INFO - 'SHELL': '/bin/bash', 23:42:43 INFO - 'SHLVL': '1', 23:42:43 INFO - 'TERM': 'linux', 23:42:43 INFO - 'TMOUT': '86400', 23:42:43 INFO - 'USER': 'cltbld', 23:42:43 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:43 INFO - '_': '/tools/buildbot/bin/python'} 23:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:43 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 23:42:43 INFO - Cleaning up... 23:42:43 INFO - Return code: 0 23:42:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:42:43 INFO - Using env: {'DISPLAY': ':0', 23:42:43 INFO - 'HOME': '/home/cltbld', 23:42:43 INFO - 'LANG': 'en_US.UTF-8', 23:42:43 INFO - 'LANGUAGE': 'en_US:en', 23:42:43 INFO - 'LOGNAME': 'cltbld', 23:42:43 INFO - 'MAIL': '/var/mail/cltbld', 23:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:43 INFO - 'MOZ_NO_REMOTE': '1', 23:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:43 INFO - 'NO_EM_RESTART': '1', 23:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:43 INFO - 'PWD': '/builds/slave/test', 23:42:43 INFO - 'SHELL': '/bin/bash', 23:42:43 INFO - 'SHLVL': '1', 23:42:43 INFO - 'TERM': 'linux', 23:42:43 INFO - 'TMOUT': '86400', 23:42:43 INFO - 'USER': 'cltbld', 23:42:43 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:43 INFO - '_': '/tools/buildbot/bin/python'} 23:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:44 INFO - Downloading/unpacking psutil>=3.1.1 23:42:44 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 23:42:44 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 23:42:44 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 23:42:44 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 23:42:44 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 23:42:44 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 23:42:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:42:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:46 INFO - Installing collected packages: psutil 23:42:46 INFO - Running setup.py install for psutil 23:42:46 INFO - building 'psutil._psutil_linux' extension 23:42:46 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 23:42:46 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 23:42:46 INFO - building 'psutil._psutil_posix' extension 23:42:46 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 23:42:46 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 23:42:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:46 INFO - Successfully installed psutil 23:42:46 INFO - Cleaning up... 23:42:46 INFO - Return code: 0 23:42:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:42:46 INFO - Using env: {'DISPLAY': ':0', 23:42:46 INFO - 'HOME': '/home/cltbld', 23:42:46 INFO - 'LANG': 'en_US.UTF-8', 23:42:46 INFO - 'LANGUAGE': 'en_US:en', 23:42:46 INFO - 'LOGNAME': 'cltbld', 23:42:46 INFO - 'MAIL': '/var/mail/cltbld', 23:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:46 INFO - 'MOZ_NO_REMOTE': '1', 23:42:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:46 INFO - 'NO_EM_RESTART': '1', 23:42:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:46 INFO - 'PWD': '/builds/slave/test', 23:42:46 INFO - 'SHELL': '/bin/bash', 23:42:46 INFO - 'SHLVL': '1', 23:42:46 INFO - 'TERM': 'linux', 23:42:46 INFO - 'TMOUT': '86400', 23:42:46 INFO - 'USER': 'cltbld', 23:42:46 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:46 INFO - '_': '/tools/buildbot/bin/python'} 23:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:42:48 INFO - Installing collected packages: mozsystemmonitor 23:42:48 INFO - Running setup.py install for mozsystemmonitor 23:42:49 INFO - Successfully installed mozsystemmonitor 23:42:49 INFO - Cleaning up... 23:42:49 INFO - Return code: 0 23:42:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:42: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')]} 23:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42: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')]} 23:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:42:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:42:49 INFO - Using env: {'DISPLAY': ':0', 23:42:49 INFO - 'HOME': '/home/cltbld', 23:42:49 INFO - 'LANG': 'en_US.UTF-8', 23:42:49 INFO - 'LANGUAGE': 'en_US:en', 23:42:49 INFO - 'LOGNAME': 'cltbld', 23:42:49 INFO - 'MAIL': '/var/mail/cltbld', 23:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:49 INFO - 'MOZ_NO_REMOTE': '1', 23:42:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:49 INFO - 'NO_EM_RESTART': '1', 23:42:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:49 INFO - 'PWD': '/builds/slave/test', 23:42:49 INFO - 'SHELL': '/bin/bash', 23:42:49 INFO - 'SHLVL': '1', 23:42:49 INFO - 'TERM': 'linux', 23:42:49 INFO - 'TMOUT': '86400', 23:42:49 INFO - 'USER': 'cltbld', 23:42:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:49 INFO - '_': '/tools/buildbot/bin/python'} 23:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:49 INFO - Downloading/unpacking jsonschema==2.5.1 23:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:42:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:51 INFO - Downloading functools32-3.2.3-2.tar.gz 23:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:42:51 INFO - warning: no files found matching '*.txt' 23:42:51 INFO - no previously-included directories found matching 'build' 23:42:51 INFO - no previously-included directories found matching 'dist' 23:42:51 INFO - no previously-included directories found matching '.git*' 23:42:51 INFO - Installing collected packages: jsonschema, functools32 23:42:51 INFO - Running setup.py install for functools32 23:42:51 INFO - warning: no files found matching '*.txt' 23:42:51 INFO - no previously-included directories found matching 'build' 23:42:51 INFO - no previously-included directories found matching 'dist' 23:42:51 INFO - no previously-included directories found matching '.git*' 23:42:51 INFO - Successfully installed jsonschema functools32 23:42:51 INFO - Cleaning up... 23:42:51 INFO - Return code: 0 23:42:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:42:51 INFO - Using env: {'DISPLAY': ':0', 23:42:51 INFO - 'HOME': '/home/cltbld', 23:42:51 INFO - 'LANG': 'en_US.UTF-8', 23:42:51 INFO - 'LANGUAGE': 'en_US:en', 23:42:51 INFO - 'LOGNAME': 'cltbld', 23:42:51 INFO - 'MAIL': '/var/mail/cltbld', 23:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:51 INFO - 'MOZ_NO_REMOTE': '1', 23:42:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:51 INFO - 'NO_EM_RESTART': '1', 23:42:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:51 INFO - 'PWD': '/builds/slave/test', 23:42:51 INFO - 'SHELL': '/bin/bash', 23:42:51 INFO - 'SHLVL': '1', 23:42:51 INFO - 'TERM': 'linux', 23:42:51 INFO - 'TMOUT': '86400', 23:42:51 INFO - 'USER': 'cltbld', 23:42:51 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:51 INFO - '_': '/tools/buildbot/bin/python'} 23:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:42:51 INFO - Cleaning up... 23:42:51 INFO - Return code: 0 23:42:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:42:52 INFO - Using env: {'DISPLAY': ':0', 23:42:52 INFO - 'HOME': '/home/cltbld', 23:42:52 INFO - 'LANG': 'en_US.UTF-8', 23:42:52 INFO - 'LANGUAGE': 'en_US:en', 23:42:52 INFO - 'LOGNAME': 'cltbld', 23:42:52 INFO - 'MAIL': '/var/mail/cltbld', 23:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:52 INFO - 'MOZ_NO_REMOTE': '1', 23:42:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:52 INFO - 'NO_EM_RESTART': '1', 23:42:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:52 INFO - 'PWD': '/builds/slave/test', 23:42:52 INFO - 'SHELL': '/bin/bash', 23:42:52 INFO - 'SHLVL': '1', 23:42:52 INFO - 'TERM': 'linux', 23:42:52 INFO - 'TMOUT': '86400', 23:42:52 INFO - 'USER': 'cltbld', 23:42:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:52 INFO - '_': '/tools/buildbot/bin/python'} 23:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:52 INFO - Downloading/unpacking blobuploader==1.2.4 23:42:52 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 23:42:52 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 23:42:52 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 23:42:52 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 23:42:52 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 23:42:52 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 23:42:54 INFO - Downloading blobuploader-1.2.4.tar.gz 23:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:42:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:42:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42:54 INFO - Downloading docopt-0.6.1.tar.gz 23:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:42:54 INFO - Installing collected packages: blobuploader, requests, docopt 23:42:54 INFO - Running setup.py install for blobuploader 23:42:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:42:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:42:55 INFO - Running setup.py install for requests 23:42:55 INFO - Running setup.py install for docopt 23:42:55 INFO - Successfully installed blobuploader requests docopt 23:42:55 INFO - Cleaning up... 23:42:55 INFO - Return code: 0 23:42:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:55 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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:55 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 23:42:55 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 23:42:55 INFO - Using env: {'DISPLAY': ':0', 23:42:55 INFO - 'HOME': '/home/cltbld', 23:42:55 INFO - 'LANG': 'en_US.UTF-8', 23:42:55 INFO - 'LANGUAGE': 'en_US:en', 23:42:55 INFO - 'LOGNAME': 'cltbld', 23:42:55 INFO - 'MAIL': '/var/mail/cltbld', 23:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:55 INFO - 'MOZ_NO_REMOTE': '1', 23:42:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:55 INFO - 'NO_EM_RESTART': '1', 23:42:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:55 INFO - 'PWD': '/builds/slave/test', 23:42:55 INFO - 'SHELL': '/bin/bash', 23:42:55 INFO - 'SHLVL': '1', 23:42:55 INFO - 'TERM': 'linux', 23:42:55 INFO - 'TMOUT': '86400', 23:42:55 INFO - 'USER': 'cltbld', 23:42:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:55 INFO - '_': '/tools/buildbot/bin/python'} 23:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:42:55 INFO - Running setup.py (path:/tmp/pip-yKbIVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:42:55 INFO - Running setup.py (path:/tmp/pip-cLWsfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:42:55 INFO - Running setup.py (path:/tmp/pip-prKwNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:42:55 INFO - Running setup.py (path:/tmp/pip-X6IPwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:42:56 INFO - Running setup.py (path:/tmp/pip-COr6Ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:42:56 INFO - Running setup.py (path:/tmp/pip-fHzCh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:42:56 INFO - Running setup.py (path:/tmp/pip-oDX_kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:42:56 INFO - Running setup.py (path:/tmp/pip-hjI39L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:42:56 INFO - Running setup.py (path:/tmp/pip-EzwCSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:42:56 INFO - Running setup.py (path:/tmp/pip-m68vZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:42:56 INFO - Running setup.py (path:/tmp/pip-gpWhNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:42:56 INFO - Running setup.py (path:/tmp/pip-dhWOYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:42:56 INFO - Running setup.py (path:/tmp/pip-dK8N2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:42:56 INFO - Running setup.py (path:/tmp/pip-4uTuZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:56 INFO - Running setup.py (path:/tmp/pip-x1fKvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:42:56 INFO - Running setup.py (path:/tmp/pip-k8U4IU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:42:56 INFO - Running setup.py (path:/tmp/pip-obLdTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:42:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:42:56 INFO - Running setup.py install for manifestparser 23:42:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozcrash 23:42:57 INFO - Running setup.py install for mozdebug 23:42:57 INFO - Running setup.py install for mozdevice 23:42:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozfile 23:42:57 INFO - Running setup.py install for mozhttpd 23:42:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozinfo 23:42:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozInstall 23:42:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozleak 23:42:57 INFO - Running setup.py install for mozlog 23:42:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Running setup.py install for moznetwork 23:42:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Running setup.py install for mozprocess 23:42:58 INFO - Running setup.py install for mozprofile 23:42:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Running setup.py install for mozrunner 23:42:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Running setup.py install for mozscreenshot 23:42:58 INFO - Running setup.py install for moztest 23:42:58 INFO - Running setup.py install for mozversion 23:42:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:42:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:42:58 INFO - Cleaning up... 23:42:58 INFO - Return code: 0 23:42:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:58 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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:42:58 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 23:42:58 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 23:42:58 INFO - Using env: {'DISPLAY': ':0', 23:42:58 INFO - 'HOME': '/home/cltbld', 23:42:58 INFO - 'LANG': 'en_US.UTF-8', 23:42:58 INFO - 'LANGUAGE': 'en_US:en', 23:42:58 INFO - 'LOGNAME': 'cltbld', 23:42:58 INFO - 'MAIL': '/var/mail/cltbld', 23:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:58 INFO - 'MOZ_NO_REMOTE': '1', 23:42:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:58 INFO - 'NO_EM_RESTART': '1', 23:42:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:58 INFO - 'PWD': '/builds/slave/test', 23:42:58 INFO - 'SHELL': '/bin/bash', 23:42:58 INFO - 'SHLVL': '1', 23:42:58 INFO - 'TERM': 'linux', 23:42:58 INFO - 'TMOUT': '86400', 23:42:58 INFO - 'USER': 'cltbld', 23:42:58 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:58 INFO - '_': '/tools/buildbot/bin/python'} 23:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:42:59 INFO - Running setup.py (path:/tmp/pip-oMfis7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:42:59 INFO - Running setup.py (path:/tmp/pip-G7bBBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:42:59 INFO - Running setup.py (path:/tmp/pip-cKcghY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:42:59 INFO - Running setup.py (path:/tmp/pip-qgaAu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:42:59 INFO - Running setup.py (path:/tmp/pip-AfaUnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:42:59 INFO - Running setup.py (path:/tmp/pip-dhfQPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:42:59 INFO - Running setup.py (path:/tmp/pip-cWTu7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:42:59 INFO - Running setup.py (path:/tmp/pip-GeLreS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:42:59 INFO - Running setup.py (path:/tmp/pip-TGOl2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:42:59 INFO - Running setup.py (path:/tmp/pip-6_u_z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:42:59 INFO - Running setup.py (path:/tmp/pip-umAgeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:42:59 INFO - Running setup.py (path:/tmp/pip-TnEtHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:42:59 INFO - Running setup.py (path:/tmp/pip-Kv0vzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:42:59 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)) 23:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:42:59 INFO - Running setup.py (path:/tmp/pip-_8OiCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:43:00 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)) 23:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:43:00 INFO - Running setup.py (path:/tmp/pip-I4vvXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:43:00 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)) 23:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:43:00 INFO - Running setup.py (path:/tmp/pip-IGR9zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:43:00 INFO - Running setup.py (path:/tmp/pip-R19qHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:43:00 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)) 23:43:00 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)) 23:43:00 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)) 23:43:00 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)) 23:43:00 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)) 23:43:00 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)) 23:43:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:43:00 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 23:43:00 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 23:43:00 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 23:43:00 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 23:43:00 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 23:43:00 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 23:43:02 INFO - Downloading blessings-1.6.tar.gz 23:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:43:02 INFO - Installing collected packages: blessings 23:43:02 INFO - Running setup.py install for blessings 23:43:02 INFO - Successfully installed blessings 23:43:02 INFO - Cleaning up... 23:43:02 INFO - Return code: 0 23:43:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:43:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:43:02 INFO - Reading from file tmpfile_stdout 23:43:02 INFO - Current package versions: 23:43:02 INFO - argparse == 1.2.1 23:43:02 INFO - blessings == 1.6 23:43:02 INFO - blobuploader == 1.2.4 23:43:02 INFO - docopt == 0.6.1 23:43:02 INFO - functools32 == 3.2.3-2 23:43:02 INFO - jsonschema == 2.5.1 23:43:02 INFO - manifestparser == 1.1 23:43:02 INFO - mozInstall == 1.12 23:43:02 INFO - mozcrash == 1.0 23:43:02 INFO - mozdebug == 0.1 23:43:02 INFO - mozdevice == 0.49 23:43:02 INFO - mozfile == 1.2 23:43:02 INFO - mozhttpd == 0.7 23:43:02 INFO - mozinfo == 0.9 23:43:02 INFO - mozleak == 0.1 23:43:02 INFO - mozlog == 3.4 23:43:02 INFO - moznetwork == 0.27 23:43:02 INFO - mozprocess == 0.23 23:43:02 INFO - mozprofile == 0.28 23:43:02 INFO - mozrunner == 6.13 23:43:02 INFO - mozscreenshot == 0.1 23:43:02 INFO - mozsystemmonitor == 0.3 23:43:02 INFO - moztest == 0.7 23:43:02 INFO - mozversion == 1.4 23:43:02 INFO - psutil == 3.1.1 23:43:02 INFO - requests == 1.2.3 23:43:02 INFO - wsgiref == 0.1.2 23:43:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:43: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')]} 23:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43: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')]} 23:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:02 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 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:43:02 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 23:43:02 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 23:43:02 INFO - Using env: {'DISPLAY': ':0', 23:43:02 INFO - 'HOME': '/home/cltbld', 23:43:02 INFO - 'LANG': 'en_US.UTF-8', 23:43:02 INFO - 'LANGUAGE': 'en_US:en', 23:43:02 INFO - 'LOGNAME': 'cltbld', 23:43:02 INFO - 'MAIL': '/var/mail/cltbld', 23:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:02 INFO - 'MOZ_NO_REMOTE': '1', 23:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:43:02 INFO - 'NO_EM_RESTART': '1', 23:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:02 INFO - 'PWD': '/builds/slave/test', 23:43:02 INFO - 'SHELL': '/bin/bash', 23:43:02 INFO - 'SHLVL': '1', 23:43:02 INFO - 'TERM': 'linux', 23:43:02 INFO - 'TMOUT': '86400', 23:43:02 INFO - 'USER': 'cltbld', 23:43:02 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:02 INFO - '_': '/tools/buildbot/bin/python'} 23:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 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)) 23:43:02 INFO - Cleaning up... 23:43:02 INFO - Return code: 0 23:43:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:43: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')]} 23:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43: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')]} 23:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8fd80ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', '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 23:43: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', 'jsonschema'] in /builds/slave/test/build 23:43: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 jsonschema 23:43:02 INFO - Using env: {'DISPLAY': ':0', 23:43:02 INFO - 'HOME': '/home/cltbld', 23:43:02 INFO - 'LANG': 'en_US.UTF-8', 23:43:02 INFO - 'LANGUAGE': 'en_US:en', 23:43:02 INFO - 'LOGNAME': 'cltbld', 23:43:02 INFO - 'MAIL': '/var/mail/cltbld', 23:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:02 INFO - 'MOZ_NO_REMOTE': '1', 23:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:43:02 INFO - 'NO_EM_RESTART': '1', 23:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:02 INFO - 'PWD': '/builds/slave/test', 23:43:02 INFO - 'SHELL': '/bin/bash', 23:43:02 INFO - 'SHLVL': '1', 23:43:02 INFO - 'TERM': 'linux', 23:43:02 INFO - 'TMOUT': '86400', 23:43:02 INFO - 'USER': 'cltbld', 23:43:02 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:02 INFO - '_': '/tools/buildbot/bin/python'} 23:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:43:03 INFO - Cleaning up... 23:43:03 INFO - Return code: 0 23:43:03 INFO - Running post-action listener: _resource_record_post_action 23:43:03 INFO - Running post-action listener: _start_resource_monitoring 23:43:03 INFO - Starting resource monitoring. 23:43:03 INFO - [mozharness: 2016-11-23 07:43:03.185592Z] Finished create-virtualenv step (success) 23:43:03 INFO - [mozharness: 2016-11-23 07:43:03.186065Z] Running install step. 23:43:03 INFO - Running pre-action listener: _resource_record_pre_action 23:43:03 INFO - Running main action method: install 23:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:43:03 INFO - Reading from file tmpfile_stdout 23:43:03 INFO - Detecting whether we're running mozinstall >=1.0... 23:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:43:03 INFO - Reading from file tmpfile_stdout 23:43:03 INFO - Output received: 23:43:03 INFO - Usage: mozinstall [options] installer 23:43:03 INFO - Options: 23:43:03 INFO - -h, --help show this help message and exit 23:43:03 INFO - -d DEST, --destination=DEST 23:43:03 INFO - Directory to install application into. [default: 23:43:03 INFO - "/builds/slave/test"] 23:43:03 INFO - --app=APP Application being installed. [default: firefox] 23:43:03 INFO - mkdir: /builds/slave/test/build/application 23:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:43:20 INFO - Reading from file tmpfile_stdout 23:43:20 INFO - Output received: 23:43:20 INFO - /builds/slave/test/build/application/firefox/firefox 23:43:20 INFO - Running post-action listener: _resource_record_post_action 23:43:20 INFO - [mozharness: 2016-11-23 07:43:20.160996Z] Finished install step (success) 23:43:20 INFO - [mozharness: 2016-11-23 07:43:20.161250Z] Running run-tests step. 23:43:20 INFO - Running pre-action listener: _resource_record_pre_action 23:43:20 INFO - Running main action method: run_tests 23:43:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:43:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:43:20 INFO - Python 2.7.3 23:43:20 INFO - Return code: 0 23:43:20 INFO - grabbing minidump binary from tooltool 23:43:20 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')]} 23:43:20 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 0x1349fc0>, '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 0x134aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134b350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:43:20 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 23:43:20 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 23:43:20 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 23:43:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:43:20 INFO - Return code: 0 23:43:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:43:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:43:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:43:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:43:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:43:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 23:43:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 23:43:20 INFO - Using env: {'DISPLAY': ':0', 23:43:20 INFO - 'HOME': '/home/cltbld', 23:43:20 INFO - 'LANG': 'en_US.UTF-8', 23:43:20 INFO - 'LANGUAGE': 'en_US:en', 23:43:20 INFO - 'LOGNAME': 'cltbld', 23:43:20 INFO - 'MAIL': '/var/mail/cltbld', 23:43:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:43:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:20 INFO - 'MOZ_NO_REMOTE': '1', 23:43:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:43:20 INFO - 'NO_EM_RESTART': '1', 23:43:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:20 INFO - 'PWD': '/builds/slave/test', 23:43:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:43:20 INFO - 'SHELL': '/bin/bash', 23:43:20 INFO - 'SHLVL': '1', 23:43:20 INFO - 'TERM': 'linux', 23:43:20 INFO - 'TMOUT': '86400', 23:43:20 INFO - 'USER': 'cltbld', 23:43:20 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939', 23:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:20 INFO - '_': '/tools/buildbot/bin/python'} 23:43:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 23:43:20 INFO - mozversion application_buildid: 20161122222435 23:43:20 INFO - mozversion application_changeset: 2622d62d1f0803bfdb497af8e3405b02fd7e478f 23:43:20 INFO - mozversion application_display_name: Nightly 23:43:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:43:20 INFO - mozversion application_name: Firefox 23:43:20 INFO - mozversion application_remotingname: firefox 23:43:20 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 23:43:20 INFO - mozversion application_vendor: Mozilla 23:43:20 INFO - mozversion application_version: 53.0a1 23:43:20 INFO - mozversion platform_buildid: 20161122222435 23:43:20 INFO - mozversion platform_changeset: 2622d62d1f0803bfdb497af8e3405b02fd7e478f 23:43:20 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 23:43:20 INFO - mozversion platform_version: 53.0a1 23:43:20 INFO - using testdate: 1479887000 23:43:20 INFO - actual date: 1479887000 23:43:20 INFO - starting webserver on 'localhost:42248' 23:43:20 INFO - SUITE-START | Running 2 tests 23:43:20 INFO - TEST-START | dromaeo_css 23:43:20 INFO - operating with platform_type : linux_ 23:43:20 INFO - Initialising browser for dromaeo_css test... 23:43:20 INFO - TEST-INFO | started process 30895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Wz49t/profile http://localhost:42248/getInfo.html) 23:43:24 INFO - PROCESS | 30895 | __metrics Screen width/height:1600/1200 23:43:24 INFO - PROCESS | 30895 | colorDepth:24 23:43:24 INFO - PROCESS | 30895 | Browser inner width/height: 1024/697 23:43:24 INFO - PROCESS | 30895 | __metrics 23:43:25 INFO - PROCESS | 30895 | 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" 23:43:25 INFO - PROCESS | 30895 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:43:26 INFO - PROCESS | 30895 | console.error: PushService: 23:43:26 INFO - PROCESS | 30895 | stateChangeProcessEnqueue: Error transitioning state 23:43:26 INFO - PROCESS | 30895 | UnknownError 23:43:26 INFO - TEST-INFO | 30895: exit 0 23:43:26 INFO - Browser initialized. 23:43:26 INFO - Running cycle 1/1 for dromaeo_css test... 23:43:26 INFO - TEST-INFO | started process 30978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Wz49t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:43:39 INFO - PROCESS | 30978 | RSS: Main: 174211072 23:43:39 INFO - PROCESS | 30978 | 23:44:29 INFO - PROCESS | 30978 | 1479887069831 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:46:43 INFO - PROCESS | 30978 | Cycle 1(1): loaded http://localhost:42248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42248/tests/dromaeo/cssquery-ext.html) 23:46:45 INFO - PROCESS | 30978 | RSS: Main: 328597504 23:46:45 INFO - PROCESS | 30978 | 23:49:48 INFO - PROCESS | 30978 | Cycle 1(1): loaded http://localhost:42248/tests/dromaeo/cssquery-ext.html (next: http://localhost:42248/tests/dromaeo/cssquery-jquery.html) 23:49:48 INFO - PROCESS | 30978 | RSS: Main: 275902464 23:49:48 INFO - PROCESS | 30978 | 23:52:51 INFO - PROCESS | 30978 | Cycle 1(1): loaded http://localhost:42248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42248/tests/dromaeo/cssquery-mootools.html) 23:52:51 INFO - PROCESS | 30978 | RSS: Main: 216563712 23:52:51 INFO - PROCESS | 30978 | 23:55:54 INFO - PROCESS | 30978 | Cycle 1(1): loaded http://localhost:42248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42248/tests/dromaeo/cssquery-prototype.html) 23:55:54 INFO - PROCESS | 30978 | RSS: Main: 219156480 23:55:54 INFO - PROCESS | 30978 | 23:58:57 INFO - PROCESS | 30978 | Cycle 1(1): loaded http://localhost:42248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42248/tests/dromaeo/cssquery-yui.html) 23:58:58 INFO - PROCESS | 30978 | RSS: Main: 216657920 23:58:58 INFO - PROCESS | 30978 | 00:02:01 INFO - PROCESS | 30978 | Cycle 1(1): loaded http://localhost:42248/tests/dromaeo/cssquery-yui.html (next: http://localhost:42248/tests/dromaeo/cssquery-dojo.html) 00:02:01 INFO - PROCESS | 30978 | RSS: Main: 312721408 00:02:01 INFO - PROCESS | 30978 | 00:02:01 INFO - PROCESS | 30978 | __start_tp_report 00:02:01 INFO - PROCESS | 30978 | _x_x_mozilla_page_load 00:02:01 INFO - PROCESS | 30978 | _x_x_mozilla_page_load_details 00:02:01 INFO - PROCESS | 30978 | |i|pagename|runs| 00:02:02 INFO - PROCESS | 30978 | |0;dojo.html;2565.91019314324;2587.2755628424025;2616.188981415761;2620.54140525408;2629.737026297372;4472.44094488189;4532.456105267364;4635.397398338216;4649.976750116249;4658.487566367697;4558.54414558544;4616.192166370888;4630.884227894304;4670.229412147;4710.599599034086;4181.414601955728;4251.043515209084;4289.914201715964;4301.268784306675;4311.676624253178;4293.720908140961;4309.935350969738;4321.805518751648;4323.502797178317;4354.303311470166;4142.896427589304;4258.446401967755;4273.722208056466;4301.8064187111495;4365.607095361417;4153.044799696087;4207.97896010518;4212.241796476633;4255.680823938217;4257.872263832059;4239.279322515149;4255.191513612434;4260.7656578888145;4299.376590394407;4303.311470164758;4589.128065667546;4600.240960241556;4618.468876079261;4621.861344159681;4646.4191976003;4763.857084287477;4795.544423279783;4799.208130658437;4845.9757701211265;4913.901721965576;4880.463149053601;4926.162552366106;4927.48261432548;4958.281049247875;4972.801087956477;4590.563896429834;4597.540245975376;4598.8620341389815;4600.148972440109;4646.465656449484;4892.559669629751;4932.161532539477;5031.993601279685;5040.218766091261;5075.11185542536;4784.976075119602;4800.687955282895;4825.806967721252;4836.516348365135;4894.8042078317185;4943.530364615356;4944.826931057396;4948.109340318705;4951.034548263054;4996.850094497171;4890.535399137077;4902.14212512816;4905.337779399807;4910.2880082388565;4958.454569997298;4687.296905464208;4692.648051396159;4743.573078422958;4792.2332426811545;4792.95207047925;4024.557298697141;4055.330870406409;4072.7963601819793;4095.201435720006;4118.176364727066;3587.6412358764437;3597;3603.621619729943;3636.6908812751212;3641.235340578455;4330.307150855848;4333.848315308949;4335.566443355639;4339.305711086211;4376.168527979674;4814.831480898151;4840.927386089212;4856.8300109495995;4859.927101093486;4884.413870335582;4281.143771245764;4281.422008028876;4312.460942382202;4319.8488052918;4324.805383757738;4377.9124417511475;4382.408374869416;4383.232934236496;4384.254676704968;4429.7785110744335;4530.388397566287;4571.908561828811;4577;4642.628589712747;4691;4857.53853383928;4889.144399729962;4895.314655948099;4918.778654960463;4962.975185124052;4626.676132670681;4639.698419602715;4647.442306923191;4653.232216684344;4687.015726697432;4766.880827979328;4797.832075877328;4837.516248375135;4845.297431872288;4887.60899128076;4891.755412229445;4895.265710143507;4905.730184839868;4935.235038569028;4990;4806.567408933212;4809.78355973989;4823.252395878644;4827.903441931212;4857.53853383928;4792.137415265286;4802.639802014793;4803.015381846643;4803.687760295569;4849.903001940012;4565.543445655408;4614.031053478807;4766.761661916959;4802.399700037496;4813.831515896927;4876.780544875418;4878.0975519529;4881.51184881509;4921.458639549718;4927.334809800775;4548.909021819612;4593.8162473502025;4607.032523170172;4728.787204575733;4760.500147484464 00:02:02 INFO - PROCESS | 30978 | |1;ext.html;534.671177226006;535.2907397698051;537.9912663755458;543.6031696861291;550.730142230307;29311.267218319554;30592.082237532883;38445.615543844586;39673.8016309918;39811.60188398115;38994.8050259749;39321.606783932155;39394.21211575774;39457.40813887794;39461.210775784464;7444.776656700307;7586.165521792598;7626.237376262385;7630.694772209105;7641.350485208736;7330.157031941315;7546.924530754704;9670.178034867045;9888.604455821795;9925.156361709263;9973.202143828512;10014.399136051823;10015.349002314862;10032.247581431386;10032.398056116619;9748.463834489101;9800.509974501247;9806;9812.116909478182;9814.558344874498;9814.411135331902;9857;9874.308798384116;9875.913649498621;9879.753006174831;37391.691290804694;37774.43338349927;37800.43299350512;37814;37853.24293514142;2939.4415061138316;2971.3463038131795;3465.5321531593163;3538.292341531704;3595.7662751921043;25585.36036599081;27718.861405693046;28036.299092522648;28085.859570702014;28097.01660441895;3213.1324542373427;3259.08745551246;3334.0497958082074;3398.4052790761516;3406.8466918988456;247520;248878.9999999982;250491;251087;251182;52815.471845281056;52827;53159.73420132875;53167;53228.15279173365;55257.723711381186;55371.723141384035;55445;55452;55478.445215548134;14464.059836110619;14785;14793.92603036978;14794.186319752302;14795.260236988108;9455.338126331228;11101.83347249792;11178.552857885594;11248.381339026268;11253.099752019818;14739.778903316459;17083.829161708225;17602;17761.424593434138;17970.55073623144;6650.235222949326;6880.451898322837;6883.862322753517;6916.100906882073;6939.895901561481;759.9772006839804;817.264461984219;884.9867251991225;933.9813203736023;973.3575839945602;26403.867980660358;26853.328666783487;27343.726562734515;27361.86319068392;27390.589141162898;922.6078916460611;936.3866667332908;950.2018304624149;965.1024547171058;995.0696099147176;13630.727385452434;14364;14565;14694.55916322512;14828.629284267763;360922;365251;365349;367134;367853;46939.530604693515;47563.762181189566;47599.762001189774;47626.285605715944;47631.28553071707;1055.8469021991768;1087.7443800706828;1125.4372813593368;1168.988310116905;1171.9296842189497;25836.612450812878;27116.1865144046;27755.86122069377;28499.715002849705;28762.856185718938;38758.22483550369;39509.604903950596;39574.40638390484;39710.40434393544;39713.2057358857;285964;288323;288426;289164;289229;23767.643485347387;24880.12919547807;24902.12842550502;25645.487090258463;25870.61194082128;39194.2161156781;39755;39835.80082099571;40452.1909561813;40705.79647101746;33705.325893482484;34344.31311373709;34405.31189376248;34636.65363346435;34658.48012279767;33794.8310258457;33827.3234535303;33870.83064584661;33974.320513590086;34002;33433.16417089495;34386.82806585951;34516.137096572784;34623.13442163966;34722.13194670052;27278.181654550393;28672.569911451763;30527.542086869158;31577.52633710542;31837.522437163;23937.52124957456;24153;24175.274741757774;24388.756112439358;24651.383715407257 00:02:02 INFO - PROCESS | 30978 | |2;jquery.html;343.8332408781742;355.5306994766907;366.2053344242993;373.21438372226464;392.4035466091539;12420.441080151393;12863.935680321598;16470.341186352533;16597;17783.46649600511;17096.31614735409;17107.40124095657;17179.312827486916;17250.568735781613;17418.129093545336;4827.879303017417;5087.643864929457;5089.211172268286;5098.235264710286;5348.893022139555;5334.226537152111;5352.946470535284;5357.410684824686;5358.089124848766;5363.168708850135;5037.773300201499;5316.016536940659;5340.839774806762;5399.055165346068;5404.08130617794;5210;5363.919541206885;5385.138377859524;5401.540869026098;5441.374241962186;5246.160614301693;5289.2859463972645;5299.364076310829;5299.523042926116;5302.072137375943;11933;12088.637340879788;12245.265284082983;12270.38648067602;12366.134370594062;1788.5870162571553;1827.378691244983;1853.1753369750465;1944.2806161720112;2369.597168481345;8887.511186884718;9214.585343659552;9296.674616388462;9418.764530886714;10519.894801051969;1656.9585760355967;1701.702202114625;1711.3839017953526;1715.5368050626387;1796.1288774944142;61286;66068.6696566519;67683.3231667682;67696;68742;9194.448333100036;9406.294527910433;9455.385399948982;9480.288978326653;9673.177779888789;12365.567205147776;12379.93810030944;12386.938065309616;12408.875911240953;12428.141527448737;6619.536632435684;6812.591244525344;7030.718771249093;7079.787606371817;7208.315210055036;6844.486663500257;6884.724611015604;7035.190953040364;7055.682494287769;7150.499465037397;3434.536337594393;3674.81625918703;3773.6981041516615;3793.829277682511;3900.5904380040256;1561.5627624265223;1663.1434811072309;1677.5088992845615;1682.3775203174903;1688.8648908087323;818.7093581778502;830.0910502999207;843.4939036578004;893.647009431271;902.2781774580441;15632.374705011673;16108.597285067734;16515.6696866062;16602.086885221186;17117.91441042812;793.3415265329762;795.9562224077731;821.2977903892206;825.558326295441;867.1068799136899;9043.68347107848;9473.478958657202;9510.619575217052;9606.442835286052;9643;277580;280813;280858;345878;359115;45092.54907450883;75198;83002.16997830065;83401;86353.5682321597;889.9644014239358;901.1349104859278;927.4296307770808;934.9579268932914;937.7749340158313;15188.671228699768;15360.692786144438;15929.362825487315;16011.119388433748;16268.267927943034;36937.63062369342;38047.429288561245;38127.23745525129;38186.42720359141;40178.19643607053;53317.733411332694;54063.72968135134;54111.45888541064;54213.45786542242;55707.72146139244;6965.930340696528;7385.446091543048;7469.7012119516785;7643.853421986746;7763.2236776321915;10816.513256903301;11000.284981476178;11554;11783.998360139483;12232.571859984857;9842.704718858446;10185.694429167137;10226.130778883879;10400.843987340348;10614.787704246026;10028.398296102258;10041.347312424668;10157.9492102539;10174.389536627827;10264.332818366782;10136.89863101389;10419;10462.37225766426;10473.162147028372;10824.079953204066;7109.28907109285;7361.742339018108;7384.929185268214;7487;7529.4729368943645;9503.904960950302;10290.3825770454;10376.377417354983;10453.320534165256;10473.895261047292 00:02:02 INFO - PROCESS | 30978 | |3;mootools.html;1141.6403832792673;1142.3831131189158;1263.2483672215033;1576.5428025872498;1586.8333824948388;5747.913781293284;5944.524438044946;6346.8730625387525;6736.898946515794;6741.359570840768;6413.90379144312;6641.335866413357;6671.766488172915;6784.626845523494;6820.215675197353;2036.9796302036975;2071.9378418647466;2074.647309957311;2076.2006627448463;2076.4289820299387;2037.4804424871636;2069.1102825784906;2076.6469700150933;2084.708140860273;2086.196814226526;1987.7018447232886;1995.4113536506766;2009.2164056018164;2012.1951219512239;2013.0840467587286;1944.9513762155918;1960.304092047317;1962.2445358536922;1962.7448431703858;1963.9509012274807;1753.8684598655025;1814.2108182940462;1967.9016049197483;1995.760508738946;2016.8689035212665;6504.219493660744;6797.422219111381;6808.8978665320055;6822;6971.048106530179;4056.918861622751;4181.97909010453;4236.957630423687;4237.194932962727;4251.319788833772;2616.8037397195285;2708.417690196617;2757.9724202757916;2763.7236276372405;2775.764060054898;805.9194080591894;823.263179454391;826.962786674601;830.0620299062082;868.2793281576339;8476.745697629081;8622.870656940151;8699.433667068166;8712.651493940235;8714.60784264716;3607.260511595169;3739.700823934079;3789.7157713171073;3833.4249862520287;3838.7312888097563;7606.695732170762;7645.694172233048;7662.693492260247;7676.961615191888;7685.385169186452;4314.654827613784;4317.546657600969;4320.351947207944;4321.546237645002;4324.048709283952;3779.6976241900584;3828.693704503633;3905.7851818150275;3914.041059940333;3916;3111.6577176510564;3196.7922085064397;3251.1872031992;3263.526788615685;3279.442494775846;1005.9446730429896;1025.4718819807806;1028.1723212813608;1040.5265604150093;1046.0742243114898;592.419428959622;684.3703792510902;689.6689588997309;697.083335413929;704.0671110778239;4188.141431006637;4262.083652014831;4334.715978241996;4367.082912588392;4444.622207112367;353.4662659384297;353.6180924601424;354.2383874669481;360.9602943676193;367.51488035793034;3052.0538633023834;3103.4568950433586;3178.507331363642;3202.8398580070902;3346.966530334714;7046.6828992695455;7343.192248852622;7388.704451821867;7585.127710313384;7948.642311096015;8104.432689711663;8107.797305067303;8123.350131989427;8130.634121464548;8141.267285944294;6289.779857704958;6369.26753423353;6372.299047104815;6426.839329016812;6476.870462590722;4468.508464069015;4560.429946256718;4607.262837945913;4622.445306563234;4721.173794586002;7534.8493030138;7572.3942084632;7620.314171724572;7645.159032506309;7650;7483.513571617827;7594.8101297468;7622.733204337822;7627.923720762721;7698.538087714755;5298.099323115002;5411.70235637028;5415.5938304626525;5421.891562168814;5425.294711687456;6127.172831667668;6237.345078766664;6310;6373.904391434225;6375.904361434675;5589.105743081088;5709.914351284651;5724.341700704305;5733.971330143323;5768.711564421678;5456.127019676833;5490.835274941758;5494.532964697885;5534.501894829324;5579.302587176603;5031;5105.106606343949;5105.693658380509;5114.2840002400435;5122.487751224848;3862.8841134766003;3912.8630497932436;3946.743461674982;3996.400539918919;4087.38689196611;3761.6614504694708;3777.112378591084;3820.350540408193;3903.8048097594437;3906.570277269555 00:02:02 INFO - PROCESS | 30978 | |4;prototype.html;272.9454109178164;282.80910385489796;289.30133727049173;296.6010715587537;296.9896053638123;4599.908001839961;4749.810007599692;4824.686395384302;4832.414658157891;4859.882227087767;4547.384404829093;4714.90570188596;4722.763861806904;4748.810047598092;4766.285057241407;3522.242717815669;3644.908877278076;3763.5886542546546;3767.6467943530142;3769.24615076985;3615.692666123383;3742.476053352519;3743.438484227374;3746.494223279863;3751.336551048745;3723;3728.098669678456;3733.607971163016;3739.8691045813403;3756.4598514609015;3722.7207959403154;3723.9441408378625;3727.366347720894;3758.8872333830027;3767.4160505121613;3189.3096658770887;3258.7393008559498;3281.5898012748407;3286.1784553861535;3314.486254630536;4575.267957126844;4579.351433483943;4590.862274131781;4591.4260717410325;4601.677882548223;4207.431996680471;4209.137126889011;4210.157968406331;4218;4235.661147108255;4703.341532185496;4898.363212782316;4901.38254376055;4914.238293064581;4928.186849169882;3469.132716820795;3471.3057388522143;3475.3570589441033;3485.588336723631;3490.1972546314237;5123.462036486152;5125.666831655931;5165.612579056586;5168.330485355402;5176.818811341585;3442.9311413771584;3542.830865814275;3543.645635436436;3547.6629720176543;3585.3904836177912;3565.536480257602;3597.9100522486624;3602.495650608917;3612.440071788877;3614.367485689994;4131.690123240769;4134.1731653669385;4136.503619565671;4141.213169497786;4159.292920203573;4696.624270058387;4810.8797280068275;4828.830990915301;4911.16510193268;4928.728919909439;1178.2223732336618;1183.988160118388;1187.3588262338412;1189.418073961629;1193.2065176657425;782.3037496628006;810.4124509730399;820.6635279535362;822.2435359469303;829.1666874790797;4266.722663026893;4299.828006879752;4300.709787063868;4316.676249281254;4335.328024156261;4939;4943.3573635427165;4946.901061978812;4956;4966.528179822911;4297.871063868089;4309.741415515079;4310.6335961443;4344.089460061766;4344.6089851913475;2505.498900219927;2632.6445929799233;2650.77468415183;2651.8276312039657;2652.204338698383;4691.554302341272;4693.671442999025;4695.835645752382;4697.248440249544;4701.64737644678;4958.082754690322;5037.697738135724;5098.719570423662;5105.412877519058;5107.284980102789;4570.702904311209;4846.5638092571835;4880.633952453509;4881.560659540658;4908.901821963541;5178.508041736029;5186.128379896577;5203.297554830049;5221.6344855859725;5223.4515375884985;4906.705597664151;5079.6698214615935;5089.9056702809075;5122.796142906489;5132.640715149903;4801.791176059052;4814.20565606671;4846.29728689331;4853.199222128309;4860.270959356125;3570.803605801706;3581.1405262736234;3585.1754096557415;3585.8565657374456;3594.1194407370363;4911.386076740408;5121.436641969306;5130.230465430066;5148.4851514848215;5157.782153673957;4597.816087356468;4966.336277347129;4985.401751789808;4987.802927297495;4992.301077849032;4955.603551715926;4962.528559786814;4963.602911766978;4970.893191755153;4979.751012449436;4712.410948631421;4927.891224474464;4929.507049295042;4931.235658472942;4936.802527898843;4961.231009193581;5027.673201242053;5043.722595257296;5049.8737531562;5053.8736531587;4896.779644916059;4902.264660300841;4915.311856340187;4931.334269873521;4934.087193869145 00:02:02 INFO - PROCESS | 30978 | |5;yui.html;640.6124294801645;661.6658587413356;699.9755008574701;729.135973870963;745.3478206569251;11728.999999999989;12494.687632809184;12565.05762067844;12565.811512827315;12611.558595449162;11474;11543.884561154386;11570.247933884291;11571.710707232323;11581.710457238572;616.1189499016405;630.6720505337222;729.7518843593177;747.3908764357059;750.4071783291193;761.2083433229435;764.9082110146791;766.9731559395423;767.7082708570736;768.0783060327601;807.4590024683476;809.1221025187679;809.465752603282;809.5062012172571;809.9878501822477;636.9999999999977;653.7973228299243;759.2597217712741;787.79123532264;805.1465446626595;585.7042193692158;586.5659412035106;586.7594286342621;586.9647821130745;587.4067192135974;10667.093297069681;10700.732481687943;10723.678289651323;10728.678139655824;10773.299735517166;10767.24629275951;10782;10959.945200273947;11239.66281011571;11324;9569.808603827885;10030.297879148462;10535.84196237057;10713.732156696067;10815.35107893529;3861.6331448512346;3969.6030396960364;3970.4242884781543;3977.0455090778296;3990.4213888986224;49042.75478622584;50037.49962500365;50135.24797128046;50152.49847501515;50622.74688626533;9210.861837072449;9418;9511.57197926095;9523.238140948708;9875.703728888146;16104.838951610569;16192.190390480664;16329.265183066791;16616.50150495487;16633.584160396087;6072.51419886408;6136.539759518054;6138.754449822047;6165.784197553064;6343.55595108338;3486.2678837443914;3498;3532.1875968527384;3547.8580856765957;3552.3960926642535;2586.120718955564;2690.2601784509416;2692.7576518113074;2693;2695.9326016849736;446.70517458477246;455.70151550734323;456.3975552271037;460.59697764456104;466.7129715225112;459.8436531579279;492.69206745783885;501.4033300372545;504.32672382369947;530.3794560364306;6686.078289127146;6784.864302714017;6922.307769223138;7003.684834182474;7203.135623725187;487.9853604391874;518.8106341185515;541.388231298638;556.5491951519282;558.3244274427907;3424.434968230214;3469.098034511045;3534.0634731796094;3563.6080031196548;3743.3074881147077;63275.68362158252;66252;66981;69083;69200.6539967297;58502.70748646229;59409.10886336708;59459;62329.37670623326;63736.12090126038;43924;54985.1752223717;55148.17277740837;55417.44582554203;56010;6817.5909445433435;6984.905585132971;7049.330313620198;7069.22238553769;7100.751473698396;36606.63393366139;37506.437403439515;38601;38741.6125838738;39194.608053919095;46423.30365044459;50639;51159.442027899204;51602;53365.19952200798;8566.700165494178;8579.356548259031;8674.826503470022;8744.650213991368;8940.240079593308;10157.84763228567;10246.38521688701;11794.528218871492;11905.46425410876;12043.518259269531;11429.085673146295;11517.827232591686;12187.146899716934;12377.752444951231;12382.442790074649;11341.376224307412;11577.131715121232;11777.528898844294;12123.878761212274;12369.196002259825;10960.397178155277;11117.66647000559;11271.774564508827;11302.152338574475;11827.172097953406;9040.864387034067;9501.667441639509;9869.506524673881;9897.950510247403;9985.950070249603;9706.757331066497;9814.06766357195;9856.21150307988;10018.677027256661;10178.745531361776 00:02:02 INFO - PROCESS | 30978 | __end_tp_report 00:02:02 INFO - PROCESS | 30978 | __start_cc_report 00:02:02 INFO - PROCESS | 30978 | _x_x_mozilla_cycle_collect,1770 00:02:02 INFO - PROCESS | 30978 | __end_cc_report 00:02:02 INFO - PROCESS | 30978 | __startTimestamp1479888121994__endTimestamp 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | ------- Summary: start ------- 00:02:02 INFO - PROCESS | 30978 | Number of tests: 6 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | [#0] dojo.html Cycles:165 Average:4544.47 Median:4647.44 stddev:464.44 (10.0%) stddev-sans-first:439.16 00:02:02 INFO - PROCESS | 30978 | Values: 2565.9 2587.3 2616.2 2620.5 2629.7 4472.4 4532.5 4635.4 4650.0 4658.5 4558.5 4616.2 4630.9 4670.2 4710.6 4181.4 4251.0 4289.9 4301.3 4311.7 4293.7 4309.9 4321.8 4323.5 4354.3 4142.9 4258.4 4273.7 4301.8 4365.6 4153.0 4208.0 4212.2 4255.7 4257.9 4239.3 4255.2 4260.8 4299.4 4303.3 4589.1 4600.2 4618.5 4621.9 4646.4 4763.9 4795.5 4799.2 4846.0 4913.9 4880.5 4926.2 4927.5 4958.3 4972.8 4590.6 4597.5 4598.9 4600.1 4646.5 4892.6 4932.2 5032.0 5040.2 5075.1 4785.0 4800.7 4825.8 4836.5 4894.8 4943.5 4944.8 4948.1 4951.0 4996.9 4890.5 4902.1 4905.3 4910.3 4958.5 4687.3 4692.6 4743.6 4792.2 4793.0 4024.6 4055.3 4072.8 4095.2 4118.2 3587.6 3597.0 3603.6 3636.7 3641.2 4330.3 4333.8 4335.6 4339.3 4376.2 4814.8 4840.9 4856.8 4859.9 4884.4 4281.1 4281.4 4312.5 4319.8 4324.8 4377.9 4382.4 4383.2 4384.3 4429.8 4530.4 4571.9 4577.0 4642.6 4691.0 4857.5 4889.1 4895.3 4918.8 4963.0 4626.7 4639.7 4647.4 4653.2 4687.0 4766.9 4797.8 4837.5 4845.3 4887.6 4891.8 4895.3 4905.7 4935.2 4990.0 4806.6 4809.8 4823.3 4827.9 4857.5 4792.1 4802.6 4803.0 4803.7 4849.9 4565.5 4614.0 4766.8 4802.4 4813.8 4876.8 4878.1 4881.5 4921.5 4927.3 4548.9 4593.8 4607.0 4728.8 4760.5 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | [#1] ext.html Cycles:180 Average:45429.18 Median:19062.01 stddev:80050.43 (419.9%) stddev-sans-first:80204.02 00:02:02 INFO - PROCESS | 30978 | Values: 534.7 535.3 538.0 543.6 550.7 29311.3 30592.1 38445.6 39673.8 39811.6 38994.8 39321.6 39394.2 39457.4 39461.2 7444.8 7586.2 7626.2 7630.7 7641.4 7330.2 7546.9 9670.2 9888.6 9925.2 9973.2 10014.4 10015.3 10032.2 10032.4 9748.5 9800.5 9806.0 9812.1 9814.6 9814.4 9857.0 9874.3 9875.9 9879.8 37391.7 37774.4 37800.4 37814.0 37853.2 2939.4 2971.3 3465.5 3538.3 3595.8 25585.4 27718.9 28036.3 28085.9 28097.0 3213.1 3259.1 3334.0 3398.4 3406.8 247520.0 248879.0 250491.0 251087.0 251182.0 52815.5 52827.0 53159.7 53167.0 53228.2 55257.7 55371.7 55445.0 55452.0 55478.4 14464.1 14785.0 14793.9 14794.2 14795.3 9455.3 11101.8 11178.6 11248.4 11253.1 14739.8 17083.8 17602.0 17761.4 17970.6 6650.2 6880.5 6883.9 6916.1 6939.9 760.0 817.3 885.0 934.0 973.4 26403.9 26853.3 27343.7 27361.9 27390.6 922.6 936.4 950.2 965.1 995.1 13630.7 14364.0 14565.0 14694.6 14828.6 360922.0 365251.0 365349.0 367134.0 367853.0 46939.5 47563.8 47599.8 47626.3 47631.3 1055.8 1087.7 1125.4 1169.0 1171.9 25836.6 27116.2 27755.9 28499.7 28762.9 38758.2 39509.6 39574.4 39710.4 39713.2 285964.0 288323.0 288426.0 289164.0 289229.0 23767.6 24880.1 24902.1 25645.5 25870.6 39194.2 39755.0 39835.8 40452.2 40705.8 33705.3 34344.3 34405.3 34636.7 34658.5 33794.8 33827.3 33870.8 33974.3 34002.0 33433.2 34386.8 34516.1 34623.1 34722.1 27278.2 28672.6 30527.5 31577.5 31837.5 23937.5 24153.0 24175.3 24388.8 24651.4 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | [#2] jquery.html Cycles:180 Average:21654.56 Median:4932.83 stddev:52041.42 (1055.0%) stddev-sans-first:52162.81 00:02:02 INFO - PROCESS | 30978 | Values: 343.8 355.5 366.2 373.2 392.4 12420.4 12863.9 16470.3 16597.0 17783.5 17096.3 17107.4 17179.3 17250.6 17418.1 4827.9 5087.6 5089.2 5098.2 5348.9 5334.2 5352.9 5357.4 5358.1 5363.2 5037.8 5316.0 5340.8 5399.1 5404.1 5210.0 5363.9 5385.1 5401.5 5441.4 5246.2 5289.3 5299.4 5299.5 5302.1 11933.0 12088.6 12245.3 12270.4 12366.1 1788.6 1827.4 1853.2 1944.3 2369.6 8887.5 9214.6 9296.7 9418.8 10519.9 1657.0 1701.7 1711.4 1715.5 1796.1 61286.0 66068.7 67683.3 67696.0 68742.0 9194.4 9406.3 9455.4 9480.3 9673.2 12365.6 12379.9 12386.9 12408.9 12428.1 6619.5 6812.6 7030.7 7079.8 7208.3 6844.5 6884.7 7035.2 7055.7 7150.5 3434.5 3674.8 3773.7 3793.8 3900.6 1561.6 1663.1 1677.5 1682.4 1688.9 818.7 830.1 843.5 893.6 902.3 15632.4 16108.6 16515.7 16602.1 17117.9 793.3 796.0 821.3 825.6 867.1 9043.7 9473.5 9510.6 9606.4 9643.0 277580.0 280813.0 280858.0 345878.0 359115.0 45092.5 75198.0 83002.2 83401.0 86353.6 890.0 901.1 927.4 935.0 937.8 15188.7 15360.7 15929.4 16011.1 16268.3 36937.6 38047.4 38127.2 38186.4 40178.2 53317.7 54063.7 54111.5 54213.5 55707.7 6965.9 7385.4 7469.7 7643.9 7763.2 10816.5 11000.3 11554.0 11784.0 12232.6 9842.7 10185.7 10226.1 10400.8 10614.8 10028.4 10041.3 10157.9 10174.4 10264.3 10136.9 10419.0 10462.4 10473.2 10824.1 7109.3 7361.7 7384.9 7487.0 7529.5 9503.9 10290.4 10376.4 10453.3 10473.9 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | [#3] mootools.html Cycles:180 Average:4373.42 Median:4514.47 stddev:2355.25 (52.2%) stddev-sans-first:2349.33 00:02:02 INFO - PROCESS | 30978 | Values: 1141.6 1142.4 1263.2 1576.5 1586.8 5747.9 5944.5 6346.9 6736.9 6741.4 6413.9 6641.3 6671.8 6784.6 6820.2 2037.0 2071.9 2074.6 2076.2 2076.4 2037.5 2069.1 2076.6 2084.7 2086.2 1987.7 1995.4 2009.2 2012.2 2013.1 1945.0 1960.3 1962.2 1962.7 1964.0 1753.9 1814.2 1967.9 1995.8 2016.9 6504.2 6797.4 6808.9 6822.0 6971.0 4056.9 4182.0 4237.0 4237.2 4251.3 2616.8 2708.4 2758.0 2763.7 2775.8 805.9 823.3 827.0 830.1 868.3 8476.7 8622.9 8699.4 8712.7 8714.6 3607.3 3739.7 3789.7 3833.4 3838.7 7606.7 7645.7 7662.7 7677.0 7685.4 4314.7 4317.5 4320.4 4321.5 4324.0 3779.7 3828.7 3905.8 3914.0 3916.0 3111.7 3196.8 3251.2 3263.5 3279.4 1005.9 1025.5 1028.2 1040.5 1046.1 592.4 684.4 689.7 697.1 704.1 4188.1 4262.1 4334.7 4367.1 4444.6 353.5 353.6 354.2 361.0 367.5 3052.1 3103.5 3178.5 3202.8 3347.0 7046.7 7343.2 7388.7 7585.1 7948.6 8104.4 8107.8 8123.4 8130.6 8141.3 6289.8 6369.3 6372.3 6426.8 6476.9 4468.5 4560.4 4607.3 4622.4 4721.2 7534.8 7572.4 7620.3 7645.2 7650.0 7483.5 7594.8 7622.7 7627.9 7698.5 5298.1 5411.7 5415.6 5421.9 5425.3 6127.2 6237.3 6310.0 6373.9 6375.9 5589.1 5709.9 5724.3 5734.0 5768.7 5456.1 5490.8 5494.5 5534.5 5579.3 5031.0 5105.1 5105.7 5114.3 5122.5 3862.9 3912.9 3946.7 3996.4 4087.4 3761.7 3777.1 3820.4 3903.8 3906.6 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | [#4] prototype.html Cycles:180 Average:4099.45 Median:4694.75 stddev:1202.56 (25.6%) stddev-sans-first:1171.14 00:02:02 INFO - PROCESS | 30978 | Values: 272.9 282.8 289.3 296.6 297.0 4599.9 4749.8 4824.7 4832.4 4859.9 4547.4 4714.9 4722.8 4748.8 4766.3 3522.2 3644.9 3763.6 3767.6 3769.2 3615.7 3742.5 3743.4 3746.5 3751.3 3723.0 3728.1 3733.6 3739.9 3756.5 3722.7 3723.9 3727.4 3758.9 3767.4 3189.3 3258.7 3281.6 3286.2 3314.5 4575.3 4579.4 4590.9 4591.4 4601.7 4207.4 4209.1 4210.2 4218.0 4235.7 4703.3 4898.4 4901.4 4914.2 4928.2 3469.1 3471.3 3475.4 3485.6 3490.2 5123.5 5125.7 5165.6 5168.3 5176.8 3442.9 3542.8 3543.6 3547.7 3585.4 3565.5 3597.9 3602.5 3612.4 3614.4 4131.7 4134.2 4136.5 4141.2 4159.3 4696.6 4810.9 4828.8 4911.2 4928.7 1178.2 1184.0 1187.4 1189.4 1193.2 782.3 810.4 820.7 822.2 829.2 4266.7 4299.8 4300.7 4316.7 4335.3 4939.0 4943.4 4946.9 4956.0 4966.5 4297.9 4309.7 4310.6 4344.1 4344.6 2505.5 2632.6 2650.8 2651.8 2652.2 4691.6 4693.7 4695.8 4697.2 4701.6 4958.1 5037.7 5098.7 5105.4 5107.3 4570.7 4846.6 4880.6 4881.6 4908.9 5178.5 5186.1 5203.3 5221.6 5223.5 4906.7 5079.7 5089.9 5122.8 5132.6 4801.8 4814.2 4846.3 4853.2 4860.3 3570.8 3581.1 3585.2 3585.9 3594.1 4911.4 5121.4 5130.2 5148.5 5157.8 4597.8 4966.3 4985.4 4987.8 4992.3 4955.6 4962.5 4963.6 4970.9 4979.8 4712.4 4927.9 4929.5 4931.2 4936.8 4961.2 5027.7 5043.7 5049.9 5053.9 4896.8 4902.3 4915.3 4931.3 4934.1 00:02:02 INFO - PROCESS | 30978 | 00:02:02 INFO - PROCESS | 30978 | [#5] yui.html Cycles:180 Average:14321.57 Median:25328.41 stddev:18413.01 (72.7%) stddev-sans-first:18436.00 00:02:02 INFO - PROCESS | 30978 | Values: 640.6 661.7 700.0 729.1 745.3 11729.0 12494.7 12565.1 12565.8 12611.6 11474.0 11543.9 11570.2 11571.7 11581.7 616.1 630.7 729.8 747.4 750.4 761.2 764.9 767.0 767.7 768.1 807.5 809.1 809.5 809.5 810.0 637.0 653.8 759.3 787.8 805.1 585.7 586.6 586.8 587.0 587.4 10667.1 10700.7 10723.7 10728.7 10773.3 10767.2 10782.0 10959.9 11239.7 11324.0 9569.8 10030.3 10535.8 10713.7 10815.4 3861.6 3969.6 3970.4 3977.0 3990.4 49042.8 50037.5 50135.2 50152.5 50622.7 9210.9 9418.0 9511.6 9523.2 9875.7 16104.8 16192.2 16329.3 16616.5 16633.6 6072.5 6136.5 6138.8 6165.8 6343.6 3486.3 3498.0 3532.2 3547.9 3552.4 2586.1 2690.3 2692.8 2693.0 2695.9 446.7 455.7 456.4 460.6 466.7 459.8 492.7 501.4 504.3 530.4 6686.1 6784.9 6922.3 7003.7 7203.1 488.0 518.8 541.4 556.5 558.3 3424.4 3469.1 3534.1 3563.6 3743.3 63275.7 66252.0 66981.0 69083.0 69200.7 58502.7 59409.1 59459.0 62329.4 63736.1 43924.0 54985.2 55148.2 55417.4 56010.0 6817.6 6984.9 7049.3 7069.2 7100.8 36606.6 37506.4 38601.0 38741.6 39194.6 46423.3 50639.0 51159.4 51602.0 53365.2 8566.7 8579.4 8674.8 8744.7 8940.2 10157.8 10246.4 11794.5 11905.5 12043.5 11429.1 11517.8 12187.1 12377.8 12382.4 11341.4 11577.1 11777.5 12123.9 12369.2 10960.4 11117.7 11271.8 11302.2 11827.2 9040.9 9501.7 9869.5 9898.0 9986.0 9706.8 9814.1 9856.2 10018.7 10178.7 00:02:02 INFO - PROCESS | 30978 | -------- Summary: end -------- 00:02:02 INFO - PROCESS | 30978 | 00:02:03 INFO - TEST-INFO | 30978: exit 0 00:02:03 INFO - TEST-OK | dromaeo_css | took 1122594ms 00:02:03 INFO - TEST-START | kraken 00:02:03 INFO - operating with platform_type : linux_ 00:02:03 INFO - Initialising browser for kraken test... 00:02:03 INFO - TEST-INFO | started process 31290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmWNdaO/profile http://localhost:42248/getInfo.html) 00:02:07 INFO - PROCESS | 31290 | __metrics Screen width/height:1600/1200 00:02:07 INFO - PROCESS | 31290 | colorDepth:24 00:02:07 INFO - PROCESS | 31290 | Browser inner width/height: 1024/697 00:02:07 INFO - PROCESS | 31290 | __metrics 00:02:07 INFO - PROCESS | 31290 | 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" 00:02:07 INFO - PROCESS | 31290 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:02:08 INFO - PROCESS | 31290 | console.error: PushService: 00:02:08 INFO - PROCESS | 31290 | stateChangeProcessEnqueue: Error transitioning state 00:02:08 INFO - PROCESS | 31290 | UnknownError 00:02:09 INFO - TEST-INFO | 31290: exit 0 00:02:09 INFO - Browser initialized. 00:02:09 INFO - Running cycle 1/1 for kraken test... 00:02:09 INFO - TEST-INFO | started process 31372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmWNdaO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:02:21 INFO - PROCESS | 31372 | RSS: Main: 174149632 00:02:21 INFO - PROCESS | 31372 | 00:02:24 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42248/tests/kraken/driver.html?testName=audio-beat-detection) 00:02:25 INFO - PROCESS | 31372 | RSS: Main: 239284224 00:02:25 INFO - PROCESS | 31372 | 00:02:28 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42248/tests/kraken/driver.html?testName=audio-dft) 00:02:28 INFO - PROCESS | 31372 | RSS: Main: 216764416 00:02:28 INFO - PROCESS | 31372 | 00:02:31 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42248/tests/kraken/driver.html?testName=audio-fft) 00:02:32 INFO - PROCESS | 31372 | RSS: Main: 299528192 00:02:32 INFO - PROCESS | 31372 | 00:02:34 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42248/tests/kraken/driver.html?testName=audio-oscillator) 00:02:35 INFO - PROCESS | 31372 | RSS: Main: 207302656 00:02:35 INFO - PROCESS | 31372 | 00:02:37 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:02:38 INFO - PROCESS | 31372 | RSS: Main: 212688896 00:02:38 INFO - PROCESS | 31372 | 00:02:41 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42248/tests/kraken/driver.html?testName=imaging-darkroom) 00:02:41 INFO - PROCESS | 31372 | RSS: Main: 252035072 00:02:41 INFO - PROCESS | 31372 | 00:02:45 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42248/tests/kraken/driver.html?testName=imaging-desaturate) 00:02:46 INFO - PROCESS | 31372 | RSS: Main: 246661120 00:02:46 INFO - PROCESS | 31372 | 00:02:49 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42248/tests/kraken/driver.html?testName=json-parse-financial) 00:02:49 INFO - PROCESS | 31372 | RSS: Main: 248705024 00:02:49 INFO - PROCESS | 31372 | 00:02:51 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:02:52 INFO - PROCESS | 31372 | RSS: Main: 212525056 00:02:52 INFO - PROCESS | 31372 | 00:02:53 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:02:54 INFO - PROCESS | 31372 | RSS: Main: 219385856 00:02:54 INFO - PROCESS | 31372 | 00:02:56 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:02:57 INFO - PROCESS | 31372 | RSS: Main: 229257216 00:02:57 INFO - PROCESS | 31372 | 00:03:00 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:03:01 INFO - PROCESS | 31372 | RSS: Main: 237056000 00:03:01 INFO - PROCESS | 31372 | 00:03:04 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:03:04 INFO - PROCESS | 31372 | RSS: Main: 233472000 00:03:04 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | Cycle 1(1): loaded http://localhost:42248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42248/tests/kraken/driver.html?testName=ai-astar) 00:03:06 INFO - PROCESS | 31372 | RSS: Main: 286691328 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | __start_tp_report 00:03:06 INFO - PROCESS | 31372 | _x_x_mozilla_page_load 00:03:06 INFO - PROCESS | 31372 | _x_x_mozilla_page_load_details 00:03:06 INFO - PROCESS | 31372 | |i|pagename|runs| 00:03:06 INFO - PROCESS | 31372 | |0;ai-astar;107;116;118;116;115;118;116;115;116;117 00:03:06 INFO - PROCESS | 31372 | |1;audio-beat-detection;157;130;132;168;132;129;129;129;168;130 00:03:06 INFO - PROCESS | 31372 | |2;audio-dft;162;160;162;162;160;162;162;161;162;160 00:03:06 INFO - PROCESS | 31372 | |3;audio-fft;76;77;78;103;77;76;78;77;105;78 00:03:06 INFO - PROCESS | 31372 | |4;audio-oscillator;83;82;120;83;84;84;103;83;83;114 00:03:06 INFO - PROCESS | 31372 | |5;imaging-gaussian-blur;117;117;116;116;116;117;117;117;117;117 00:03:06 INFO - PROCESS | 31372 | |6;imaging-darkroom;159;157;165;170;157;170;158;165;165;163 00:03:06 INFO - PROCESS | 31372 | |7;imaging-desaturate;96;96;96;96;95;97;96;96;95;95 00:03:06 INFO - PROCESS | 31372 | |8;json-parse-financial;66;65;65;65;69;66;65;65;66;68 00:03:06 INFO - PROCESS | 31372 | |9;json-stringify-tinderbox;67;66;66;67;67;67;66;66;66;66 00:03:06 INFO - PROCESS | 31372 | |10;stanford-crypto-aes;95;85;92;85;86;95;92;88;86;87 00:03:06 INFO - PROCESS | 31372 | |11;stanford-crypto-ccm;147;144;143;144;143;149;148;144;196;144 00:03:06 INFO - PROCESS | 31372 | |12;stanford-crypto-pbkdf2;188;188;180;184;184;180;187;186;186;187 00:03:06 INFO - PROCESS | 31372 | |13;stanford-crypto-sha256-iterative;74;72;70;71;73;70;73;71;77;69 00:03:06 INFO - PROCESS | 31372 | __end_tp_report 00:03:06 INFO - PROCESS | 31372 | __start_cc_report 00:03:06 INFO - PROCESS | 31372 | _x_x_mozilla_cycle_collect,1951 00:03:06 INFO - PROCESS | 31372 | __end_cc_report 00:03:06 INFO - PROCESS | 31372 | __startTimestamp1479888186867__endTimestamp 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | ------- Summary: start ------- 00:03:06 INFO - PROCESS | 31372 | Number of tests: 14 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.00 stddev:3.13 (2.7%) stddev-sans-first:1.12 00:03:06 INFO - PROCESS | 31372 | Values: 107.0 116.0 118.0 116.0 115.0 118.0 116.0 115.0 116.0 117.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:132.00 stddev:16.82 (12.7%) stddev-sans-first:16.73 00:03:06 INFO - PROCESS | 31372 | Values: 157.0 130.0 132.0 168.0 132.0 129.0 129.0 129.0 168.0 130.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 00:03:06 INFO - PROCESS | 31372 | Values: 162.0 160.0 162.0 162.0 160.0 162.0 162.0 161.0 162.0 160.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#3] audio-fft Cycles:10 Average:82.50 Median:77.00 stddev:11.37 (14.8%) stddev-sans-first:11.81 00:03:06 INFO - PROCESS | 31372 | Values: 76.0 77.0 78.0 103.0 77.0 76.0 78.0 77.0 105.0 78.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:83.00 stddev:14.69 (17.7%) stddev-sans-first:15.22 00:03:06 INFO - PROCESS | 31372 | Values: 83.0 82.0 120.0 83.0 84.0 84.0 103.0 83.0 83.0 114.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 00:03:06 INFO - PROCESS | 31372 | Values: 117.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#6] imaging-darkroom Cycles:10 Average:162.90 Median:165.00 stddev:4.98 (3.0%) stddev-sans-first:5.07 00:03:06 INFO - PROCESS | 31372 | Values: 159.0 157.0 165.0 170.0 157.0 170.0 158.0 165.0 165.0 163.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 00:03:06 INFO - PROCESS | 31372 | Values: 96.0 96.0 96.0 96.0 95.0 97.0 96.0 96.0 95.0 95.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:66.00 stddev:1.41 (2.1%) stddev-sans-first:1.50 00:03:06 INFO - PROCESS | 31372 | Values: 66.0 65.0 65.0 65.0 69.0 66.0 65.0 65.0 66.0 68.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 00:03:06 INFO - PROCESS | 31372 | Values: 67.0 66.0 66.0 67.0 67.0 67.0 66.0 66.0 66.0 66.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:90.00 stddev:4.01 (4.5%) stddev-sans-first:3.64 00:03:06 INFO - PROCESS | 31372 | Values: 95.0 85.0 92.0 85.0 86.0 95.0 92.0 88.0 86.0 87.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:145.50 stddev:16.23 (11.2%) stddev-sans-first:17.18 00:03:06 INFO - PROCESS | 31372 | Values: 147.0 144.0 143.0 144.0 143.0 149.0 148.0 144.0 196.0 144.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:186.50 stddev:2.98 (1.6%) stddev-sans-first:2.96 00:03:06 INFO - PROCESS | 31372 | Values: 188.0 188.0 180.0 184.0 184.0 180.0 187.0 186.0 186.0 187.0 00:03:06 INFO - PROCESS | 31372 | 00:03:06 INFO - PROCESS | 31372 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:72.50 stddev:2.36 (3.3%) stddev-sans-first:2.39 00:03:06 INFO - PROCESS | 31372 | Values: 74.0 72.0 70.0 71.0 73.0 70.0 73.0 71.0 77.0 69.0 00:03:06 INFO - PROCESS | 31372 | -------- Summary: end -------- 00:03:06 INFO - PROCESS | 31372 | 00:03:07 INFO - TEST-INFO | 31372: exit 0 00:03:08 INFO - TEST-OK | kraken | took 65009ms 00:03:08 INFO - SUITE-END | took 1187s 00:03:08 INFO - Completed test suite (00:19:48) 00:03:08 INFO - javascript benchmark 00:03:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2565.91019314324, 2587.2755628424025, 2616.188981415761, 2620.54140525408, 2629.737026297372, 4472.44094488189, 4532.456105267364, 4635.397398338216, 4649.976750116249, 4658.487566367697, 4558.54414558544, 4616.192166370888, 4630.884227894304, 4670.229412147, 4710.599599034086, 4181.414601955728, 4251.043515209084, 4289.914201715964, 4301.268784306675, 4311.676624253178, 4293.720908140961, 4309.935350969738, 4321.805518751648, 4323.502797178317, 4354.303311470166, 4142.896427589304, 4258.446401967755, 4273.722208056466, 4301.8064187111495, 4365.607095361417, 4153.044799696087, 4207.97896010518, 4212.241796476633, 4255.680823938217, 4257.872263832059, 4239.279322515149, 4255.191513612434, 4260.7656578888145, 4299.376590394407, 4303.311470164758, 4589.128065667546, 4600.240960241556, 4618.468876079261, 4621.861344159681, 4646.4191976003, 4763.857084287477, 4795.544423279783, 4799.208130658437, 4845.9757701211265, 4913.901721965576, 4880.463149053601, 4926.162552366106, 4927.48261432548, 4958.281049247875, 4972.801087956477, 4590.563896429834, 4597.540245975376, 4598.8620341389815, 4600.148972440109, 4646.465656449484, 4892.559669629751, 4932.161532539477, 5031.993601279685, 5040.218766091261, 5075.11185542536, 4784.976075119602, 4800.687955282895, 4825.806967721252, 4836.516348365135, 4894.8042078317185, 4943.530364615356, 4944.826931057396, 4948.109340318705, 4951.034548263054, 4996.850094497171, 4890.535399137077, 4902.14212512816, 4905.337779399807, 4910.2880082388565, 4958.454569997298, 4687.296905464208, 4692.648051396159, 4743.573078422958, 4792.2332426811545, 4792.95207047925, 4024.557298697141, 4055.330870406409, 4072.7963601819793, 4095.201435720006, 4118.176364727066, 3587.6412358764437, 3597.0, 3603.621619729943, 3636.6908812751212, 3641.235340578455, 4330.307150855848, 4333.848315308949, 4335.566443355639, 4339.305711086211, 4376.168527979674, 4814.831480898151, 4840.927386089212, 4856.8300109495995, 4859.927101093486, 4884.413870335582, 4281.143771245764, 4281.422008028876, 4312.460942382202, 4319.8488052918, 4324.805383757738, 4377.9124417511475, 4382.408374869416, 4383.232934236496, 4384.254676704968, 4429.7785110744335, 4530.388397566287, 4571.908561828811, 4577.0, 4642.628589712747, 4691.0, 4857.53853383928, 4889.144399729962, 4895.314655948099, 4918.778654960463, 4962.975185124052, 4626.676132670681, 4639.698419602715, 4647.442306923191, 4653.232216684344, 4687.015726697432, 4766.880827979328, 4797.832075877328, 4837.516248375135, 4845.297431872288, 4887.60899128076, 4891.755412229445, 4895.265710143507, 4905.730184839868, 4935.235038569028, 4990.0, 4806.567408933212, 4809.78355973989, 4823.252395878644, 4827.903441931212, 4857.53853383928, 4792.137415265286, 4802.639802014793, 4803.015381846643, 4803.687760295569, 4849.903001940012, 4565.543445655408, 4614.031053478807, 4766.761661916959, 4802.399700037496, 4813.831515896927, 4876.780544875418, 4878.0975519529, 4881.51184881509, 4921.458639549718, 4927.334809800775, 4548.909021819612, 4593.8162473502025, 4607.032523170172, 4728.787204575733, 4760.500147484464], "value": 4515.527988447831, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.671177226006, 535.2907397698051, 537.9912663755458, 543.6031696861291, 550.730142230307, 29311.267218319554, 30592.082237532883, 38445.615543844586, 39673.8016309918, 39811.60188398115, 38994.8050259749, 39321.606783932155, 39394.21211575774, 39457.40813887794, 39461.210775784464, 7444.776656700307, 7586.165521792598, 7626.237376262385, 7630.694772209105, 7641.350485208736, 7330.157031941315, 7546.924530754704, 9670.178034867045, 9888.604455821795, 9925.156361709263, 9973.202143828512, 10014.399136051823, 10015.349002314862, 10032.247581431386, 10032.398056116619, 9748.463834489101, 9800.509974501247, 9806.0, 9812.116909478182, 9814.558344874498, 9814.411135331902, 9857.0, 9874.308798384116, 9875.913649498621, 9879.753006174831, 37391.691290804694, 37774.43338349927, 37800.43299350512, 37814.0, 37853.24293514142, 2939.4415061138316, 2971.3463038131795, 3465.5321531593163, 3538.292341531704, 3595.7662751921043, 25585.36036599081, 27718.861405693046, 28036.299092522648, 28085.859570702014, 28097.01660441895, 3213.1324542373427, 3259.08745551246, 3334.0497958082074, 3398.4052790761516, 3406.8466918988456, 247520.0, 248878.9999999982, 250491.0, 251087.0, 251182.0, 52815.471845281056, 52827.0, 53159.73420132875, 53167.0, 53228.15279173365, 55257.723711381186, 55371.723141384035, 55445.0, 55452.0, 55478.445215548134, 14464.059836110619, 14785.0, 14793.92603036978, 14794.186319752302, 14795.260236988108, 9455.338126331228, 11101.83347249792, 11178.552857885594, 11248.381339026268, 11253.099752019818, 14739.778903316459, 17083.829161708225, 17602.0, 17761.424593434138, 17970.55073623144, 6650.235222949326, 6880.451898322837, 6883.862322753517, 6916.100906882073, 6939.895901561481, 759.9772006839804, 817.264461984219, 884.9867251991225, 933.9813203736023, 973.3575839945602, 26403.867980660358, 26853.328666783487, 27343.726562734515, 27361.86319068392, 27390.589141162898, 922.6078916460611, 936.3866667332908, 950.2018304624149, 965.1024547171058, 995.0696099147176, 13630.727385452434, 14364.0, 14565.0, 14694.55916322512, 14828.629284267763, 360922.0, 365251.0, 365349.0, 367134.0, 367853.0, 46939.530604693515, 47563.762181189566, 47599.762001189774, 47626.285605715944, 47631.28553071707, 1055.8469021991768, 1087.7443800706828, 1125.4372813593368, 1168.988310116905, 1171.9296842189497, 25836.612450812878, 27116.1865144046, 27755.86122069377, 28499.715002849705, 28762.856185718938, 38758.22483550369, 39509.604903950596, 39574.40638390484, 39710.40434393544, 39713.2057358857, 285964.0, 288323.0, 288426.0, 289164.0, 289229.0, 23767.643485347387, 24880.12919547807, 24902.12842550502, 25645.487090258463, 25870.61194082128, 39194.2161156781, 39755.0, 39835.80082099571, 40452.1909561813, 40705.79647101746, 33705.325893482484, 34344.31311373709, 34405.31189376248, 34636.65363346435, 34658.48012279767, 33794.8310258457, 33827.3234535303, 33870.83064584661, 33974.320513590086, 34002.0, 33433.16417089495, 34386.82806585951, 34516.137096572784, 34623.13442163966, 34722.13194670052, 27278.181654550393, 28672.569911451763, 30527.542086869158, 31577.52633710542, 31837.522437163, 23937.52124957456, 24153.0, 24175.274741757774, 24388.756112439358, 24651.383715407257], "value": 17387.51159853561, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8332408781742, 355.5306994766907, 366.2053344242993, 373.21438372226464, 392.4035466091539, 12420.441080151393, 12863.935680321598, 16470.341186352533, 16597.0, 17783.46649600511, 17096.31614735409, 17107.40124095657, 17179.312827486916, 17250.568735781613, 17418.129093545336, 4827.879303017417, 5087.643864929457, 5089.211172268286, 5098.235264710286, 5348.893022139555, 5334.226537152111, 5352.946470535284, 5357.410684824686, 5358.089124848766, 5363.168708850135, 5037.773300201499, 5316.016536940659, 5340.839774806762, 5399.055165346068, 5404.08130617794, 5210.0, 5363.919541206885, 5385.138377859524, 5401.540869026098, 5441.374241962186, 5246.160614301693, 5289.2859463972645, 5299.364076310829, 5299.523042926116, 5302.072137375943, 11933.0, 12088.637340879788, 12245.265284082983, 12270.38648067602, 12366.134370594062, 1788.5870162571553, 1827.378691244983, 1853.1753369750465, 1944.2806161720112, 2369.597168481345, 8887.511186884718, 9214.585343659552, 9296.674616388462, 9418.764530886714, 10519.894801051969, 1656.9585760355967, 1701.702202114625, 1711.3839017953526, 1715.5368050626387, 1796.1288774944142, 61286.0, 66068.6696566519, 67683.3231667682, 67696.0, 68742.0, 9194.448333100036, 9406.294527910433, 9455.385399948982, 9480.288978326653, 9673.177779888789, 12365.567205147776, 12379.93810030944, 12386.938065309616, 12408.875911240953, 12428.141527448737, 6619.536632435684, 6812.591244525344, 7030.718771249093, 7079.787606371817, 7208.315210055036, 6844.486663500257, 6884.724611015604, 7035.190953040364, 7055.682494287769, 7150.499465037397, 3434.536337594393, 3674.81625918703, 3773.6981041516615, 3793.829277682511, 3900.5904380040256, 1561.5627624265223, 1663.1434811072309, 1677.5088992845615, 1682.3775203174903, 1688.8648908087323, 818.7093581778502, 830.0910502999207, 843.4939036578004, 893.647009431271, 902.2781774580441, 15632.374705011673, 16108.597285067734, 16515.6696866062, 16602.086885221186, 17117.91441042812, 793.3415265329762, 795.9562224077731, 821.2977903892206, 825.558326295441, 867.1068799136899, 9043.68347107848, 9473.478958657202, 9510.619575217052, 9606.442835286052, 9643.0, 277580.0, 280813.0, 280858.0, 345878.0, 359115.0, 45092.54907450883, 75198.0, 83002.16997830065, 83401.0, 86353.5682321597, 889.9644014239358, 901.1349104859278, 927.4296307770808, 934.9579268932914, 937.7749340158313, 15188.671228699768, 15360.692786144438, 15929.362825487315, 16011.119388433748, 16268.267927943034, 36937.63062369342, 38047.429288561245, 38127.23745525129, 38186.42720359141, 40178.19643607053, 53317.733411332694, 54063.72968135134, 54111.45888541064, 54213.45786542242, 55707.72146139244, 6965.930340696528, 7385.446091543048, 7469.7012119516785, 7643.853421986746, 7763.2236776321915, 10816.513256903301, 11000.284981476178, 11554.0, 11783.998360139483, 12232.571859984857, 9842.704718858446, 10185.694429167137, 10226.130778883879, 10400.843987340348, 10614.787704246026, 10028.398296102258, 10041.347312424668, 10157.9492102539, 10174.389536627827, 10264.332818366782, 10136.89863101389, 10419.0, 10462.37225766426, 10473.162147028372, 10824.079953204066, 7109.28907109285, 7361.742339018108, 7384.929185268214, 7487.0, 7529.4729368943645, 9503.904960950302, 10290.3825770454, 10376.377417354983, 10453.320534165256, 10473.895261047292], "value": 7879.482324727938, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1141.6403832792673, 1142.3831131189158, 1263.2483672215033, 1576.5428025872498, 1586.8333824948388, 5747.913781293284, 5944.524438044946, 6346.8730625387525, 6736.898946515794, 6741.359570840768, 6413.90379144312, 6641.335866413357, 6671.766488172915, 6784.626845523494, 6820.215675197353, 2036.9796302036975, 2071.9378418647466, 2074.647309957311, 2076.2006627448463, 2076.4289820299387, 2037.4804424871636, 2069.1102825784906, 2076.6469700150933, 2084.708140860273, 2086.196814226526, 1987.7018447232886, 1995.4113536506766, 2009.2164056018164, 2012.1951219512239, 2013.0840467587286, 1944.9513762155918, 1960.304092047317, 1962.2445358536922, 1962.7448431703858, 1963.9509012274807, 1753.8684598655025, 1814.2108182940462, 1967.9016049197483, 1995.760508738946, 2016.8689035212665, 6504.219493660744, 6797.422219111381, 6808.8978665320055, 6822.0, 6971.048106530179, 4056.918861622751, 4181.97909010453, 4236.957630423687, 4237.194932962727, 4251.319788833772, 2616.8037397195285, 2708.417690196617, 2757.9724202757916, 2763.7236276372405, 2775.764060054898, 805.9194080591894, 823.263179454391, 826.962786674601, 830.0620299062082, 868.2793281576339, 8476.745697629081, 8622.870656940151, 8699.433667068166, 8712.651493940235, 8714.60784264716, 3607.260511595169, 3739.700823934079, 3789.7157713171073, 3833.4249862520287, 3838.7312888097563, 7606.695732170762, 7645.694172233048, 7662.693492260247, 7676.961615191888, 7685.385169186452, 4314.654827613784, 4317.546657600969, 4320.351947207944, 4321.546237645002, 4324.048709283952, 3779.6976241900584, 3828.693704503633, 3905.7851818150275, 3914.041059940333, 3916.0, 3111.6577176510564, 3196.7922085064397, 3251.1872031992, 3263.526788615685, 3279.442494775846, 1005.9446730429896, 1025.4718819807806, 1028.1723212813608, 1040.5265604150093, 1046.0742243114898, 592.419428959622, 684.3703792510902, 689.6689588997309, 697.083335413929, 704.0671110778239, 4188.141431006637, 4262.083652014831, 4334.715978241996, 4367.082912588392, 4444.622207112367, 353.4662659384297, 353.6180924601424, 354.2383874669481, 360.9602943676193, 367.51488035793034, 3052.0538633023834, 3103.4568950433586, 3178.507331363642, 3202.8398580070902, 3346.966530334714, 7046.6828992695455, 7343.192248852622, 7388.704451821867, 7585.127710313384, 7948.642311096015, 8104.432689711663, 8107.797305067303, 8123.350131989427, 8130.634121464548, 8141.267285944294, 6289.779857704958, 6369.26753423353, 6372.299047104815, 6426.839329016812, 6476.870462590722, 4468.508464069015, 4560.429946256718, 4607.262837945913, 4622.445306563234, 4721.173794586002, 7534.8493030138, 7572.3942084632, 7620.314171724572, 7645.159032506309, 7650.0, 7483.513571617827, 7594.8101297468, 7622.733204337822, 7627.923720762721, 7698.538087714755, 5298.099323115002, 5411.70235637028, 5415.5938304626525, 5421.891562168814, 5425.294711687456, 6127.172831667668, 6237.345078766664, 6310.0, 6373.904391434225, 6375.904361434675, 5589.105743081088, 5709.914351284651, 5724.341700704305, 5733.971330143323, 5768.711564421678, 5456.127019676833, 5490.835274941758, 5494.532964697885, 5534.501894829324, 5579.302587176603, 5031.0, 5105.106606343949, 5105.693658380509, 5114.2840002400435, 5122.487751224848, 3862.8841134766003, 3912.8630497932436, 3946.743461674982, 3996.400539918919, 4087.38689196611, 3761.6614504694708, 3777.112378591084, 3820.350540408193, 3903.8048097594437, 3906.570277269555], "value": 3515.660814145069, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.9454109178164, 282.80910385489796, 289.30133727049173, 296.6010715587537, 296.9896053638123, 4599.908001839961, 4749.810007599692, 4824.686395384302, 4832.414658157891, 4859.882227087767, 4547.384404829093, 4714.90570188596, 4722.763861806904, 4748.810047598092, 4766.285057241407, 3522.242717815669, 3644.908877278076, 3763.5886542546546, 3767.6467943530142, 3769.24615076985, 3615.692666123383, 3742.476053352519, 3743.438484227374, 3746.494223279863, 3751.336551048745, 3723.0, 3728.098669678456, 3733.607971163016, 3739.8691045813403, 3756.4598514609015, 3722.7207959403154, 3723.9441408378625, 3727.366347720894, 3758.8872333830027, 3767.4160505121613, 3189.3096658770887, 3258.7393008559498, 3281.5898012748407, 3286.1784553861535, 3314.486254630536, 4575.267957126844, 4579.351433483943, 4590.862274131781, 4591.4260717410325, 4601.677882548223, 4207.431996680471, 4209.137126889011, 4210.157968406331, 4218.0, 4235.661147108255, 4703.341532185496, 4898.363212782316, 4901.38254376055, 4914.238293064581, 4928.186849169882, 3469.132716820795, 3471.3057388522143, 3475.3570589441033, 3485.588336723631, 3490.1972546314237, 5123.462036486152, 5125.666831655931, 5165.612579056586, 5168.330485355402, 5176.818811341585, 3442.9311413771584, 3542.830865814275, 3543.645635436436, 3547.6629720176543, 3585.3904836177912, 3565.536480257602, 3597.9100522486624, 3602.495650608917, 3612.440071788877, 3614.367485689994, 4131.690123240769, 4134.1731653669385, 4136.503619565671, 4141.213169497786, 4159.292920203573, 4696.624270058387, 4810.8797280068275, 4828.830990915301, 4911.16510193268, 4928.728919909439, 1178.2223732336618, 1183.988160118388, 1187.3588262338412, 1189.418073961629, 1193.2065176657425, 782.3037496628006, 810.4124509730399, 820.6635279535362, 822.2435359469303, 829.1666874790797, 4266.722663026893, 4299.828006879752, 4300.709787063868, 4316.676249281254, 4335.328024156261, 4939.0, 4943.3573635427165, 4946.901061978812, 4956.0, 4966.528179822911, 4297.871063868089, 4309.741415515079, 4310.6335961443, 4344.089460061766, 4344.6089851913475, 2505.498900219927, 2632.6445929799233, 2650.77468415183, 2651.8276312039657, 2652.204338698383, 4691.554302341272, 4693.671442999025, 4695.835645752382, 4697.248440249544, 4701.64737644678, 4958.082754690322, 5037.697738135724, 5098.719570423662, 5105.412877519058, 5107.284980102789, 4570.702904311209, 4846.5638092571835, 4880.633952453509, 4881.560659540658, 4908.901821963541, 5178.508041736029, 5186.128379896577, 5203.297554830049, 5221.6344855859725, 5223.4515375884985, 4906.705597664151, 5079.6698214615935, 5089.9056702809075, 5122.796142906489, 5132.640715149903, 4801.791176059052, 4814.20565606671, 4846.29728689331, 4853.199222128309, 4860.270959356125, 3570.803605801706, 3581.1405262736234, 3585.1754096557415, 3585.8565657374456, 3594.1194407370363, 4911.386076740408, 5121.436641969306, 5130.230465430066, 5148.4851514848215, 5157.782153673957, 4597.816087356468, 4966.336277347129, 4985.401751789808, 4987.802927297495, 4992.301077849032, 4955.603551715926, 4962.528559786814, 4963.602911766978, 4970.893191755153, 4979.751012449436, 4712.410948631421, 4927.891224474464, 4929.507049295042, 4931.235658472942, 4936.802527898843, 4961.231009193581, 5027.673201242053, 5043.722595257296, 5049.8737531562, 5053.8736531587, 4896.779644916059, 4902.264660300841, 4915.311856340187, 4931.334269873521, 4934.087193869145], "value": 3711.207481003586, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.6124294801645, 661.6658587413356, 699.9755008574701, 729.135973870963, 745.3478206569251, 11728.999999999989, 12494.687632809184, 12565.05762067844, 12565.811512827315, 12611.558595449162, 11474.0, 11543.884561154386, 11570.247933884291, 11571.710707232323, 11581.710457238572, 616.1189499016405, 630.6720505337222, 729.7518843593177, 747.3908764357059, 750.4071783291193, 761.2083433229435, 764.9082110146791, 766.9731559395423, 767.7082708570736, 768.0783060327601, 807.4590024683476, 809.1221025187679, 809.465752603282, 809.5062012172571, 809.9878501822477, 636.9999999999977, 653.7973228299243, 759.2597217712741, 787.79123532264, 805.1465446626595, 585.7042193692158, 586.5659412035106, 586.7594286342621, 586.9647821130745, 587.4067192135974, 10667.093297069681, 10700.732481687943, 10723.678289651323, 10728.678139655824, 10773.299735517166, 10767.24629275951, 10782.0, 10959.945200273947, 11239.66281011571, 11324.0, 9569.808603827885, 10030.297879148462, 10535.84196237057, 10713.732156696067, 10815.35107893529, 3861.6331448512346, 3969.6030396960364, 3970.4242884781543, 3977.0455090778296, 3990.4213888986224, 49042.75478622584, 50037.49962500365, 50135.24797128046, 50152.49847501515, 50622.74688626533, 9210.861837072449, 9418.0, 9511.57197926095, 9523.238140948708, 9875.703728888146, 16104.838951610569, 16192.190390480664, 16329.265183066791, 16616.50150495487, 16633.584160396087, 6072.51419886408, 6136.539759518054, 6138.754449822047, 6165.784197553064, 6343.55595108338, 3486.2678837443914, 3498.0, 3532.1875968527384, 3547.8580856765957, 3552.3960926642535, 2586.120718955564, 2690.2601784509416, 2692.7576518113074, 2693.0, 2695.9326016849736, 446.70517458477246, 455.70151550734323, 456.3975552271037, 460.59697764456104, 466.7129715225112, 459.8436531579279, 492.69206745783885, 501.4033300372545, 504.32672382369947, 530.3794560364306, 6686.078289127146, 6784.864302714017, 6922.307769223138, 7003.684834182474, 7203.135623725187, 487.9853604391874, 518.8106341185515, 541.388231298638, 556.5491951519282, 558.3244274427907, 3424.434968230214, 3469.098034511045, 3534.0634731796094, 3563.6080031196548, 3743.3074881147077, 63275.68362158252, 66252.0, 66981.0, 69083.0, 69200.6539967297, 58502.70748646229, 59409.10886336708, 59459.0, 62329.37670623326, 63736.12090126038, 43924.0, 54985.1752223717, 55148.17277740837, 55417.44582554203, 56010.0, 6817.5909445433435, 6984.905585132971, 7049.330313620198, 7069.22238553769, 7100.751473698396, 36606.63393366139, 37506.437403439515, 38601.0, 38741.6125838738, 39194.608053919095, 46423.30365044459, 50639.0, 51159.442027899204, 51602.0, 53365.19952200798, 8566.700165494178, 8579.356548259031, 8674.826503470022, 8744.650213991368, 8940.240079593308, 10157.84763228567, 10246.38521688701, 11794.528218871492, 11905.46425410876, 12043.518259269531, 11429.085673146295, 11517.827232591686, 12187.146899716934, 12377.752444951231, 12382.442790074649, 11341.376224307412, 11577.131715121232, 11777.528898844294, 12123.878761212274, 12369.196002259825, 10960.397178155277, 11117.66647000559, 11271.774564508827, 11302.152338574475, 11827.172097953406, 9040.864387034067, 9501.667441639509, 9869.506524673881, 9897.950510247403, 9985.950070249603, 9706.757331066497, 9814.06766357195, 9856.21150307988, 10018.677027256661, 10178.745531361776], "value": 5888.749109501956, "unit": "score"}], "name": "dromaeo_css", "value": 6018.770100910046, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 118.0, 116.0, 115.0, 118.0, 116.0, 115.0, 116.0, 117.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 130.0, 132.0, 168.0, 132.0, 129.0, 129.0, 129.0, 168.0, 130.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 162.0, 160.0, 162.0, 162.0, 161.0, 162.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 103.0, 77.0, 76.0, 78.0, 77.0, 105.0, 78.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 120.0, 83.0, 84.0, 84.0, 103.0, 83.0, 83.0, 114.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 165.0, 170.0, 157.0, 170.0, 158.0, 165.0, 165.0, 163.0], "value": 162.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 97.0, 96.0, 96.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 69.0, 66.0, 65.0, 65.0, 66.0, 68.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 85.0, 92.0, 85.0, 86.0, 95.0, 92.0, 88.0, 86.0, 87.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 144.0, 143.0, 144.0, 143.0, 149.0, 148.0, 144.0, 196.0, 144.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 188.0, 180.0, 184.0, 184.0, 180.0, 187.0, 186.0, 186.0, 187.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 70.0, 71.0, 73.0, 70.0, 73.0, 71.0, 77.0, 69.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1595.6000000000001, "alertThreshold": 2.0}]} 00:03:08 INFO - Return code: 0 00:03:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:03:08 INFO - # TBPL SUCCESS # 00:03:08 INFO - Running post-action listener: _resource_record_post_action 00:03:08 INFO - [mozharness: 2016-11-23 08:03:08.657627Z] Finished run-tests step (success) 00:03:08 INFO - Running post-run listener: _resource_record_post_run 00:03:08 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 390426624; Read time: 12; Write time: 212680 00:03:08 INFO - TinderboxPrint: CPU usage
13.0% 00:03:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 00:03:08 INFO - TinderboxPrint: I/O write bytes / time
390,426,624 / 212,680 00:03:08 INFO - TinderboxPrint: CPU idle
8,359.4 (86.9%) 00:03:08 INFO - TinderboxPrint: CPU user
1,206.1 (12.5%) 00:03:08 INFO - TinderboxPrint: Swap in / out
0 / 0 00:03:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99676160; Read time: 0; Write time: 49144 00:03:08 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290750464; Read time: 12; Write time: 163536 00:03:09 INFO - Running post-run listener: _upload_blobber_files 00:03:09 INFO - Blob upload gear active. 00:03:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:03:09 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 00:03:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:03:09 INFO - (blobuploader) - INFO - Open directory for files ... 00:03:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:03:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:03:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:11 INFO - (blobuploader) - INFO - Done attempting. 00:03:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:03:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:03:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:12 INFO - (blobuploader) - INFO - Done attempting. 00:03:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:03:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:03:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:15 INFO - (blobuploader) - INFO - Done attempting. 00:03:15 INFO - (blobuploader) - INFO - Iteration through files over. 00:03:15 INFO - Return code: 0 00:03:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:03:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:03:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9e097a84651c1caa407efc41ec4a535f3d4b174b9e17bbefe988c9d44b9f2951b58744ae257c062224b6d92c38697c5dba8f1c8f732c1f83b171e28975581b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0955ff830006571261420b9c40d2fc4a447ad52f06a5bb5b50e468c3dfd4d0090ce302c6eb2f90f31c90035678c63908895a4e12e9e8ea7e00b2fc2e478a95db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0ceab7d591a6fd0dea28e0ad97f0a549e3979f54441d09171e665b74098206f48c035ba36d76bdd58fa92fbac24d798c21f783e25044279b1b59e53d9b2e02c6"} 00:03:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:03:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:03:15 INFO - Contents: 00:03:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9e097a84651c1caa407efc41ec4a535f3d4b174b9e17bbefe988c9d44b9f2951b58744ae257c062224b6d92c38697c5dba8f1c8f732c1f83b171e28975581b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0955ff830006571261420b9c40d2fc4a447ad52f06a5bb5b50e468c3dfd4d0090ce302c6eb2f90f31c90035678c63908895a4e12e9e8ea7e00b2fc2e478a95db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0ceab7d591a6fd0dea28e0ad97f0a549e3979f54441d09171e665b74098206f48c035ba36d76bdd58fa92fbac24d798c21f783e25044279b1b59e53d9b2e02c6"} 00:03:15 INFO - Running post-run listener: copy_logs_to_upload_dir 00:03:15 INFO - Copying logs to upload dir... 00:03:15 INFO - mkdir: /builds/slave/test/build/upload/logs 00:03:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.059883 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-11-23 00:03:15.552708) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 00:03:15.556061) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9e097a84651c1caa407efc41ec4a535f3d4b174b9e17bbefe988c9d44b9f2951b58744ae257c062224b6d92c38697c5dba8f1c8f732c1f83b171e28975581b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0955ff830006571261420b9c40d2fc4a447ad52f06a5bb5b50e468c3dfd4d0090ce302c6eb2f90f31c90035678c63908895a4e12e9e8ea7e00b2fc2e478a95db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0ceab7d591a6fd0dea28e0ad97f0a549e3979f54441d09171e665b74098206f48c035ba36d76bdd58fa92fbac24d798c21f783e25044279b1b59e53d9b2e02c6"} build_url:https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011464 build_url: 'https://queue.taskcluster.net/v1/task/bDyxsyeDTVupIGY3arknrg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9e097a84651c1caa407efc41ec4a535f3d4b174b9e17bbefe988c9d44b9f2951b58744ae257c062224b6d92c38697c5dba8f1c8f732c1f83b171e28975581b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0955ff830006571261420b9c40d2fc4a447ad52f06a5bb5b50e468c3dfd4d0090ce302c6eb2f90f31c90035678c63908895a4e12e9e8ea7e00b2fc2e478a95db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0ceab7d591a6fd0dea28e0ad97f0a549e3979f54441d09171e665b74098206f48c035ba36d76bdd58fa92fbac24d798c21f783e25044279b1b59e53d9b2e02c6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 00:03:15.581965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 00:03:15.582321) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1479886796.330579-900752939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004578 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 00:03:15.664767) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 00:03:15.665194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 00:03:15.665543) ========= ========= Total master_lag: 0.66 =========