builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1483746361.91 results: success (0) revision: da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:01.911065) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:01.911543) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:01.911823) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:01.926769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:01.927055) ========= 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=bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-06 15:46:02-- 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% 211M=0s 2017-01-06 15:46:02 (211 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115166 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.056564) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.056926) ========= 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=bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014868 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.107261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.107652) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e --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=bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-06 15:46:02,419 truncating revision to first 12 chars 2017-01-06 15:46:02,419 Setting DEBUG logging. 2017-01-06 15:46:02,419 attempt 1/10 2017-01-06 15:46:02,419 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/da4d2276cd29?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 15:46:02,967 unpacking tar archive at: graphics-da4d2276cd29/testing/mozharness/ program finished with exit code 0 elapsedTime=0.849873 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.971383) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.971860) ========= script_repo_revision: da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.972279) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.972588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 15:46:02.981395) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-06 15:46:02.981710) ========= /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=bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:46:03 INFO - MultiFileLogger online at 20170106 15:46:03 in /builds/slave/test 15:46:03 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 15:46:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:46:03 INFO - {'append_to_log': False, 15:46:03 INFO - 'base_work_dir': '/builds/slave/test', 15:46:03 INFO - 'blob_upload_branch': 'Graphics', 15:46:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:46:03 INFO - 'branch': 'Graphics', 15:46:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:46:03 INFO - 'config_files': ('talos/linux_config.py',), 15:46:03 INFO - 'default_actions': ('clobber', 15:46:03 INFO - 'read-buildbot-config', 15:46:03 INFO - 'download-and-extract', 15:46:03 INFO - 'populate-webroot', 15:46:03 INFO - 'create-virtualenv', 15:46:03 INFO - 'install', 15:46:03 INFO - 'run-tests'), 15:46:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:46:03 INFO - 'download_minidump_stackwalk': True, 15:46:03 INFO - 'download_symbols': 'ondemand', 15:46:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:46:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:46:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:46:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:46:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:46:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:46:03 INFO - 'installer_path': 'installer.exe', 15:46:03 INFO - 'log_level': 'info', 15:46:03 INFO - 'log_name': 'talos', 15:46:03 INFO - 'log_to_console': True, 15:46:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:46:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:46:03 INFO - 'opt_config_files': (), 15:46:03 INFO - 'pip_index': False, 15:46:03 INFO - 'sps_profile': False, 15:46:03 INFO - 'sps_profile_interval': 0, 15:46:03 INFO - 'suite': 'dromaeojs', 15:46:03 INFO - 'system_bits': '32', 15:46:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:46:03 INFO - 'title': 'talos-linux64-ix-055', 15:46:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:46:03 INFO - 'use_talos_json': True, 15:46:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:46:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:46:03 INFO - 'work_dir': 'build'} 15:46:03 INFO - [mozharness: 2017-01-06 23:46:03.385602Z] Running clobber step. 15:46:03 INFO - Running pre-action listener: _resource_record_pre_action 15:46:03 INFO - Running main action method: clobber 15:46:03 INFO - rmtree: /builds/slave/test/build 15:46:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:46:04 INFO - Running post-action listener: _resource_record_post_action 15:46:04 INFO - [mozharness: 2017-01-06 23:46:04.448013Z] Finished clobber step (success) 15:46:04 INFO - [mozharness: 2017-01-06 23:46:04.448113Z] Running read-buildbot-config step. 15:46:04 INFO - Running pre-action listener: _resource_record_pre_action 15:46:04 INFO - Running main action method: read_buildbot_config 15:46:04 INFO - Using buildbot properties: 15:46:04 INFO - { 15:46:04 INFO - "project": "", 15:46:04 INFO - "product": "firefox", 15:46:04 INFO - "who": "kgupta@mozilla.com", 15:46:04 INFO - "installer_path": "public/build/target.tar.bz2", 15:46:04 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 15:46:04 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 15:46:04 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 15:46:04 INFO - "stage_platform": "linux64", 15:46:04 INFO - "basedir": "/builds/slave/test", 15:46:04 INFO - "buildnumber": 43, 15:46:04 INFO - "platform": "ubuntu64_hw", 15:46:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:46:04 INFO - "slavebuilddir": "test", 15:46:04 INFO - "taskId": "TUCEHy5MQNOYjFKMKrV-HQ", 15:46:04 INFO - "branch": "graphics", 15:46:04 INFO - "script_repo_revision": "default", 15:46:04 INFO - "revision": "da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e", 15:46:04 INFO - "slavename": "talos-linux64-ix-055", 15:46:04 INFO - "repo_path": "projects/graphics" 15:46:04 INFO - } 15:46:04 INFO - Finding installer, test and symbols from parent task. 15:46:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TUCEHy5MQNOYjFKMKrV-HQ'}, attempt #1 15:46:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TUCEHy5MQNOYjFKMKrV-HQ'}, attempt #1 15:46:05 INFO - Task dependencies: aVOp32d_TR6wBhPhsR1clA 15:46:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA'}, attempt #1 15:46:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2 15:46:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/test_packages.json 15:46:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.crashreporter-symbols.zip 15:46:05 INFO - Running post-action listener: _resource_record_post_action 15:46:05 INFO - [mozharness: 2017-01-06 23:46:05.903374Z] Finished read-buildbot-config step (success) 15:46:05 INFO - [mozharness: 2017-01-06 23:46:05.903592Z] Running download-and-extract step. 15:46:05 INFO - Running pre-action listener: _resource_record_pre_action 15:46:05 INFO - Running main action method: download_and_extract 15:46:05 INFO - mkdir: /builds/slave/test/build/tests 15:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:05 INFO - trying https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.test_packages.json 15:46:05 INFO - Downloading https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:46:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:46:06 INFO - Downloaded 896 bytes. 15:46:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:46:06 INFO - Using the following test package requirements: 15:46:06 INFO - {u'common': [u'target.common.tests.zip'], 15:46:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:46:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:46:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:46:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:46:06 INFO - u'mozbase': [u'target.common.tests.zip'], 15:46:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:46:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:46:06 INFO - u'web-platform': [u'target.common.tests.zip', 15:46:06 INFO - u'target.web-platform.tests.zip'], 15:46:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:46:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:46:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.common.tests.zip 15:46:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:06 INFO - Fetch https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.common.tests.zip into memory 15:46:07 INFO - Expected file size: 20246889 15:46:07 INFO - Obtained file size: 20246889 15:46:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:46:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.common.tests.zip 15:46:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:11 INFO - Fetch https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.common.tests.zip into memory 15:46:12 INFO - Expected file size: 20246889 15:46:12 INFO - Obtained file size: 20246889 15:46:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.talos.tests.zip 15:46:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:46:16 INFO - Fetch https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.talos.tests.zip into memory 15:46:17 INFO - Expected file size: 14403730 15:46:17 INFO - Obtained file size: 14403730 15:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:17 INFO - trying https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2 15:46:17 INFO - Downloading https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:46:18 INFO - Downloaded 55957477 bytes. 15:46:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2 15:46:18 INFO - mkdir: /builds/slave/test/properties 15:46:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:18 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:18 INFO - Contents: 15:46:18 INFO - build_url:https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2 15:46:18 INFO - Running post-action listener: _resource_record_post_action 15:46:18 INFO - Running post-action listener: set_extra_try_arguments 15:46:18 INFO - [mozharness: 2017-01-06 23:46:18.670721Z] Finished download-and-extract step (success) 15:46:18 INFO - [mozharness: 2017-01-06 23:46:18.670817Z] Running populate-webroot step. 15:46:18 INFO - Running pre-action listener: _resource_record_pre_action 15:46:18 INFO - Running main action method: populate_webroot 15:46:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:46:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:46:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:46:18 INFO - '--tppagecycles', 15:46:18 INFO - '7'], 15:46:18 INFO - 'tests': ['tsvgm']}}, 15:46:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['damp', 'tps']}, 15:46:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'tests': ['damp', 'tps']}, 15:46:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['dromaeo_dom']}, 15:46:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['basic_compositor_video']}, 15:46:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:46:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['a11yr', 15:46:18 INFO - 'ts_paint', 15:46:18 INFO - 'tpaint', 15:46:18 INFO - 'sessionrestore', 15:46:18 INFO - 'sessionrestore_no_auto_restore', 15:46:18 INFO - 'tabpaint']}, 15:46:18 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:18 INFO - 'ts_paint', 15:46:18 INFO - 'tpaint', 15:46:18 INFO - 'sessionrestore', 15:46:18 INFO - 'sessionrestore_no_auto_restore', 15:46:18 INFO - 'tabpaint']}, 15:46:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:46:18 INFO - 'ts_paint', 15:46:18 INFO - 'tpaint', 15:46:18 INFO - 'sessionrestore', 15:46:18 INFO - 'sessionrestore_no_auto_restore', 15:46:18 INFO - 'tabpaint']}, 15:46:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:46:18 INFO - 'ts_paint', 15:46:18 INFO - 'tpaint', 15:46:18 INFO - 'sessionrestore', 15:46:18 INFO - 'sessionrestore_no_auto_restore', 15:46:18 INFO - 'tabpaint']}, 15:46:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['a11yr', 15:46:18 INFO - 'ts_paint', 15:46:18 INFO - 'tpaint', 15:46:18 INFO - 'sessionrestore', 15:46:18 INFO - 'sessionrestore_no_auto_restore', 15:46:18 INFO - 'tabpaint']}, 15:46:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['a11yr', 15:46:18 INFO - 'ts_paint', 15:46:18 INFO - 'tpaint', 15:46:18 INFO - 'sessionrestore', 15:46:18 INFO - 'sessionrestore_no_auto_restore', 15:46:18 INFO - 'tabpaint']}, 15:46:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['tsvgx', 15:46:18 INFO - 'tsvgr_opacity', 15:46:18 INFO - 'tart', 15:46:18 INFO - 'tscrollx', 15:46:18 INFO - 'cart', 15:46:18 INFO - 'tsvg_static']}, 15:46:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:18 INFO - 'tsvgr_opacity', 15:46:18 INFO - 'tart', 15:46:18 INFO - 'tscrollx', 15:46:18 INFO - 'cart', 15:46:18 INFO - 'tsvg_static']}, 15:46:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'talos_options': ['--disable-e10s'], 15:46:18 INFO - 'tests': ['tp5o']}, 15:46:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:46:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'talos_options': ['--disable-e10s', 15:46:18 INFO - '--xperf_path', 15:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:18 INFO - 'tests': ['tp5n']}, 15:46:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:46:18 INFO - 'talos_options': ['--xperf_path', 15:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:18 INFO - 'tests': ['tp5n']}}, 15:46:18 INFO - 'talos.zip': {'path': '', 15:46:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:46:18 INFO - Running post-action listener: _resource_record_post_action 15:46:18 INFO - [mozharness: 2017-01-06 23:46:18.683976Z] Finished populate-webroot step (success) 15:46:18 INFO - [mozharness: 2017-01-06 23:46:18.684069Z] Running create-virtualenv step. 15:46:18 INFO - Running pre-action listener: _resource_record_pre_action 15:46:18 INFO - Running main action method: create_virtualenv 15:46:18 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:18 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 15:46:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:46:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:18 INFO - Using real prefix '/usr' 15:46:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:19 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:21 INFO - Installing pip.................done. 15:46:21 INFO - Return code: 0 15:46:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:46:21 INFO - Reading from file tmpfile_stdout 15:46:21 INFO - Output received: 15:46:21 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) 15:46:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46:21 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')]} 15:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:21 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')]} 15:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:21 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:21 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 15:46:21 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 15:46:21 INFO - Using env: {'DISPLAY': ':0', 15:46:21 INFO - 'HOME': '/home/cltbld', 15:46:21 INFO - 'LANG': 'en_US.UTF-8', 15:46:21 INFO - 'LANGUAGE': 'en_US:en', 15:46:21 INFO - 'LOGNAME': 'cltbld', 15:46:21 INFO - 'MAIL': '/var/mail/cltbld', 15:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:21 INFO - 'MOZ_NO_REMOTE': '1', 15:46:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:21 INFO - 'NO_EM_RESTART': '1', 15:46:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:21 INFO - 'PWD': '/builds/slave/test', 15:46:21 INFO - 'SHELL': '/bin/bash', 15:46:21 INFO - 'SHLVL': '1', 15:46:21 INFO - 'TERM': 'linux', 15:46:21 INFO - 'TMOUT': '86400', 15:46:21 INFO - 'USER': 'cltbld', 15:46:21 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:21 INFO - '_': '/tools/buildbot/bin/python'} 15:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:21 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 15:46:21 INFO - Cleaning up... 15:46:21 INFO - Return code: 0 15:46:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:46:21 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')]} 15:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:21 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')]} 15:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:21 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:21 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 15:46:21 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 15:46:21 INFO - Using env: {'DISPLAY': ':0', 15:46:21 INFO - 'HOME': '/home/cltbld', 15:46:21 INFO - 'LANG': 'en_US.UTF-8', 15:46:21 INFO - 'LANGUAGE': 'en_US:en', 15:46:21 INFO - 'LOGNAME': 'cltbld', 15:46:21 INFO - 'MAIL': '/var/mail/cltbld', 15:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:21 INFO - 'MOZ_NO_REMOTE': '1', 15:46:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:21 INFO - 'NO_EM_RESTART': '1', 15:46:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:21 INFO - 'PWD': '/builds/slave/test', 15:46:21 INFO - 'SHELL': '/bin/bash', 15:46:21 INFO - 'SHLVL': '1', 15:46:21 INFO - 'TERM': 'linux', 15:46:21 INFO - 'TMOUT': '86400', 15:46:21 INFO - 'USER': 'cltbld', 15:46:21 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:21 INFO - '_': '/tools/buildbot/bin/python'} 15:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:21 INFO - Downloading/unpacking psutil>=3.1.1 15:46:21 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 15:46:21 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 15:46:21 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 15:46:21 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 15:46:21 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 15:46:21 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 15:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:46:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:24 INFO - Installing collected packages: psutil 15:46:24 INFO - Running setup.py install for psutil 15:46:24 INFO - building 'psutil._psutil_linux' extension 15:46:24 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 15:46:24 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 15:46:24 INFO - building 'psutil._psutil_posix' extension 15:46:24 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 15:46:24 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 15:46:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:24 INFO - Successfully installed psutil 15:46:24 INFO - Cleaning up... 15:46:24 INFO - Return code: 0 15:46:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:24 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:24 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 15:46:24 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 15:46:24 INFO - Using env: {'DISPLAY': ':0', 15:46:24 INFO - 'HOME': '/home/cltbld', 15:46:24 INFO - 'LANG': 'en_US.UTF-8', 15:46:24 INFO - 'LANGUAGE': 'en_US:en', 15:46:24 INFO - 'LOGNAME': 'cltbld', 15:46:24 INFO - 'MAIL': '/var/mail/cltbld', 15:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:24 INFO - 'MOZ_NO_REMOTE': '1', 15:46:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:24 INFO - 'NO_EM_RESTART': '1', 15:46:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:24 INFO - 'PWD': '/builds/slave/test', 15:46:24 INFO - 'SHELL': '/bin/bash', 15:46:24 INFO - 'SHLVL': '1', 15:46:24 INFO - 'TERM': 'linux', 15:46:24 INFO - 'TMOUT': '86400', 15:46:24 INFO - 'USER': 'cltbld', 15:46:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:24 INFO - '_': '/tools/buildbot/bin/python'} 15:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:46:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:46:26 INFO - Installing collected packages: mozsystemmonitor 15:46:26 INFO - Running setup.py install for mozsystemmonitor 15:46:26 INFO - Successfully installed mozsystemmonitor 15:46:26 INFO - Cleaning up... 15:46:27 INFO - Return code: 0 15:46:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:46:27 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')]} 15:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:27 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')]} 15:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:27 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:27 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 15:46:27 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 15:46:27 INFO - Using env: {'DISPLAY': ':0', 15:46:27 INFO - 'HOME': '/home/cltbld', 15:46:27 INFO - 'LANG': 'en_US.UTF-8', 15:46:27 INFO - 'LANGUAGE': 'en_US:en', 15:46:27 INFO - 'LOGNAME': 'cltbld', 15:46:27 INFO - 'MAIL': '/var/mail/cltbld', 15:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:27 INFO - 'MOZ_NO_REMOTE': '1', 15:46:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:27 INFO - 'NO_EM_RESTART': '1', 15:46:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:27 INFO - 'PWD': '/builds/slave/test', 15:46:27 INFO - 'SHELL': '/bin/bash', 15:46:27 INFO - 'SHLVL': '1', 15:46:27 INFO - 'TERM': 'linux', 15:46:27 INFO - 'TMOUT': '86400', 15:46:27 INFO - 'USER': 'cltbld', 15:46:27 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:27 INFO - '_': '/tools/buildbot/bin/python'} 15:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:27 INFO - Downloading/unpacking jsonschema==2.5.1 15:46:27 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 15:46:27 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 15:46:27 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 15:46:27 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 15:46:27 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 15:46:27 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 15:46:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:46:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:46:29 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 15:46:29 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 15:46:29 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 15:46:29 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 15:46:29 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 15:46:29 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 15:46:29 INFO - Downloading functools32-3.2.3-2.tar.gz 15:46:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:46:29 INFO - warning: no files found matching '*.txt' 15:46:29 INFO - no previously-included directories found matching 'build' 15:46:29 INFO - no previously-included directories found matching 'dist' 15:46:29 INFO - no previously-included directories found matching '.git*' 15:46:29 INFO - Installing collected packages: jsonschema, functools32 15:46:29 INFO - Running setup.py install for functools32 15:46:29 INFO - warning: no files found matching '*.txt' 15:46:29 INFO - no previously-included directories found matching 'build' 15:46:29 INFO - no previously-included directories found matching 'dist' 15:46:29 INFO - no previously-included directories found matching '.git*' 15:46:29 INFO - Successfully installed jsonschema functools32 15:46:29 INFO - Cleaning up... 15:46:29 INFO - Return code: 0 15:46:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:29 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:29 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 15:46:29 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 15:46:29 INFO - Using env: {'DISPLAY': ':0', 15:46:29 INFO - 'HOME': '/home/cltbld', 15:46:29 INFO - 'LANG': 'en_US.UTF-8', 15:46:29 INFO - 'LANGUAGE': 'en_US:en', 15:46:29 INFO - 'LOGNAME': 'cltbld', 15:46:29 INFO - 'MAIL': '/var/mail/cltbld', 15:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:29 INFO - 'MOZ_NO_REMOTE': '1', 15:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:29 INFO - 'NO_EM_RESTART': '1', 15:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:29 INFO - 'PWD': '/builds/slave/test', 15:46:29 INFO - 'SHELL': '/bin/bash', 15:46:29 INFO - 'SHLVL': '1', 15:46:29 INFO - 'TERM': 'linux', 15:46:29 INFO - 'TMOUT': '86400', 15:46:29 INFO - 'USER': 'cltbld', 15:46:29 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:29 INFO - '_': '/tools/buildbot/bin/python'} 15:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:46:29 INFO - Cleaning up... 15:46:29 INFO - Return code: 0 15:46:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:29 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:29 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 15:46:29 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 15:46:29 INFO - Using env: {'DISPLAY': ':0', 15:46:29 INFO - 'HOME': '/home/cltbld', 15:46:29 INFO - 'LANG': 'en_US.UTF-8', 15:46:29 INFO - 'LANGUAGE': 'en_US:en', 15:46:29 INFO - 'LOGNAME': 'cltbld', 15:46:29 INFO - 'MAIL': '/var/mail/cltbld', 15:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:29 INFO - 'MOZ_NO_REMOTE': '1', 15:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:29 INFO - 'NO_EM_RESTART': '1', 15:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:29 INFO - 'PWD': '/builds/slave/test', 15:46:29 INFO - 'SHELL': '/bin/bash', 15:46:29 INFO - 'SHLVL': '1', 15:46:29 INFO - 'TERM': 'linux', 15:46:29 INFO - 'TMOUT': '86400', 15:46:29 INFO - 'USER': 'cltbld', 15:46:29 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:29 INFO - '_': '/tools/buildbot/bin/python'} 15:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:30 INFO - Downloading/unpacking blobuploader==1.2.4 15:46:30 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 15:46:30 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 15:46:30 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 15:46:30 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 15:46:30 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 15:46:30 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 15:46:32 INFO - Downloading blobuploader-1.2.4.tar.gz 15:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:46:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:46:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 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 15:46:32 INFO - Downloading docopt-0.6.1.tar.gz 15:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:46:32 INFO - Installing collected packages: blobuploader, requests, docopt 15:46:32 INFO - Running setup.py install for blobuploader 15:46:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:46:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:46:33 INFO - Running setup.py install for requests 15:46:33 INFO - Running setup.py install for docopt 15:46:33 INFO - Successfully installed blobuploader requests docopt 15:46:33 INFO - Cleaning up... 15:46:33 INFO - Return code: 0 15:46:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:33 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')]} 15:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:33 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')]} 15:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:33 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:33 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 15:46:33 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 15:46:33 INFO - Using env: {'DISPLAY': ':0', 15:46:33 INFO - 'HOME': '/home/cltbld', 15:46:33 INFO - 'LANG': 'en_US.UTF-8', 15:46:33 INFO - 'LANGUAGE': 'en_US:en', 15:46:33 INFO - 'LOGNAME': 'cltbld', 15:46:33 INFO - 'MAIL': '/var/mail/cltbld', 15:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:33 INFO - 'MOZ_NO_REMOTE': '1', 15:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:33 INFO - 'NO_EM_RESTART': '1', 15:46:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:33 INFO - 'PWD': '/builds/slave/test', 15:46:33 INFO - 'SHELL': '/bin/bash', 15:46:33 INFO - 'SHLVL': '1', 15:46:33 INFO - 'TERM': 'linux', 15:46:33 INFO - 'TMOUT': '86400', 15:46:33 INFO - 'USER': 'cltbld', 15:46:33 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:33 INFO - '_': '/tools/buildbot/bin/python'} 15:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:33 INFO - Running setup.py (path:/tmp/pip-8w8zJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:33 INFO - Running setup.py (path:/tmp/pip-K_eCCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:33 INFO - Running setup.py (path:/tmp/pip-5KNWFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:34 INFO - Running setup.py (path:/tmp/pip-5KYkSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:34 INFO - Running setup.py (path:/tmp/pip-RZwt5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:34 INFO - Running setup.py (path:/tmp/pip-sp0KxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:34 INFO - Running setup.py (path:/tmp/pip-GHCsl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:34 INFO - Running setup.py (path:/tmp/pip-ZCWJrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:34 INFO - Running setup.py (path:/tmp/pip-prxql0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:34 INFO - Running setup.py (path:/tmp/pip-NYL9io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:34 INFO - Running setup.py (path:/tmp/pip-0lz30h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:34 INFO - Running setup.py (path:/tmp/pip-xencl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:34 INFO - Running setup.py (path:/tmp/pip-gyDm_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:34 INFO - Running setup.py (path:/tmp/pip-GQtqRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:34 INFO - Running setup.py (path:/tmp/pip-loPw9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:34 INFO - Running setup.py (path:/tmp/pip-lJf_KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:34 INFO - Running setup.py (path:/tmp/pip-Yw758a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:46:35 INFO - Running setup.py install for manifestparser 15:46:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Running setup.py install for mozcrash 15:46:35 INFO - Running setup.py install for mozdebug 15:46:35 INFO - Running setup.py install for mozdevice 15:46:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Running setup.py install for mozfile 15:46:35 INFO - Running setup.py install for mozhttpd 15:46:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Running setup.py install for mozinfo 15:46:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Running setup.py install for mozInstall 15:46:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:46:35 INFO - Running setup.py install for mozleak 15:46:35 INFO - Running setup.py install for mozlog 15:46:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Running setup.py install for moznetwork 15:46:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Running setup.py install for mozprocess 15:46:36 INFO - Running setup.py install for mozprofile 15:46:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Running setup.py install for mozrunner 15:46:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Running setup.py install for mozscreenshot 15:46:36 INFO - Running setup.py install for moztest 15:46:36 INFO - Running setup.py install for mozversion 15:46:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:46:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:46:36 INFO - Cleaning up... 15:46:36 INFO - Return code: 0 15:46:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:36 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')]} 15:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:36 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')]} 15:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:36 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:36 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 15:46:36 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 15:46:36 INFO - Using env: {'DISPLAY': ':0', 15:46:36 INFO - 'HOME': '/home/cltbld', 15:46:36 INFO - 'LANG': 'en_US.UTF-8', 15:46:36 INFO - 'LANGUAGE': 'en_US:en', 15:46:36 INFO - 'LOGNAME': 'cltbld', 15:46:36 INFO - 'MAIL': '/var/mail/cltbld', 15:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:36 INFO - 'MOZ_NO_REMOTE': '1', 15:46:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:36 INFO - 'NO_EM_RESTART': '1', 15:46:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:36 INFO - 'PWD': '/builds/slave/test', 15:46:36 INFO - 'SHELL': '/bin/bash', 15:46:36 INFO - 'SHLVL': '1', 15:46:36 INFO - 'TERM': 'linux', 15:46:36 INFO - 'TMOUT': '86400', 15:46:36 INFO - 'USER': 'cltbld', 15:46:36 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:36 INFO - '_': '/tools/buildbot/bin/python'} 15:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:37 INFO - Running setup.py (path:/tmp/pip-MRrGoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:37 INFO - Running setup.py (path:/tmp/pip-HHYHkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:37 INFO - Running setup.py (path:/tmp/pip-bPcqY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:37 INFO - Running setup.py (path:/tmp/pip-N7xs_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:37 INFO - Running setup.py (path:/tmp/pip-zuFUgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:37 INFO - Running setup.py (path:/tmp/pip-R71dsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:37 INFO - Running setup.py (path:/tmp/pip-2glK0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:37 INFO - Running setup.py (path:/tmp/pip-eOgPoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:37 INFO - Running setup.py (path:/tmp/pip-SpgJ7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:37 INFO - Running setup.py (path:/tmp/pip-3MrMwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:37 INFO - Running setup.py (path:/tmp/pip-6U6myI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:37 INFO - Running setup.py (path:/tmp/pip-7gNQfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:37 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)) 15:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:37 INFO - Running setup.py (path:/tmp/pip-4DxIeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:38 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)) 15:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:38 INFO - Running setup.py (path:/tmp/pip-7oejLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:38 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)) 15:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:38 INFO - Running setup.py (path:/tmp/pip-Uj1uEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:38 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)) 15:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:38 INFO - Running setup.py (path:/tmp/pip-vL00iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:38 INFO - Running setup.py (path:/tmp/pip-0LUW2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:38 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)) 15:46:38 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)) 15:46:38 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)) 15:46:38 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)) 15:46:38 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)) 15:46:38 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)) 15:46:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:46:38 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 15:46:38 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 15:46:38 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 15:46:38 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 15:46:38 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 15:46:38 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 15:46:40 INFO - Downloading blessings-1.6.tar.gz 15:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:46:40 INFO - Installing collected packages: blessings 15:46:40 INFO - Running setup.py install for blessings 15:46:40 INFO - Successfully installed blessings 15:46:40 INFO - Cleaning up... 15:46:40 INFO - Return code: 0 15:46:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:40 INFO - Reading from file tmpfile_stdout 15:46:40 INFO - Current package versions: 15:46:40 INFO - argparse == 1.2.1 15:46:40 INFO - blessings == 1.6 15:46:40 INFO - blobuploader == 1.2.4 15:46:40 INFO - docopt == 0.6.1 15:46:40 INFO - functools32 == 3.2.3-2 15:46:40 INFO - jsonschema == 2.5.1 15:46:40 INFO - manifestparser == 1.1 15:46:40 INFO - mozInstall == 1.12 15:46:40 INFO - mozcrash == 1.0 15:46:40 INFO - mozdebug == 0.1 15:46:40 INFO - mozdevice == 0.49 15:46:40 INFO - mozfile == 1.2 15:46:40 INFO - mozhttpd == 0.7 15:46:40 INFO - mozinfo == 0.9 15:46:40 INFO - mozleak == 0.1 15:46:40 INFO - mozlog == 3.4 15:46:40 INFO - moznetwork == 0.27 15:46:40 INFO - mozprocess == 0.23 15:46:40 INFO - mozprofile == 0.28 15:46:40 INFO - mozrunner == 6.13 15:46:40 INFO - mozscreenshot == 0.1 15:46:40 INFO - mozsystemmonitor == 0.3 15:46:40 INFO - moztest == 0.8 15:46:40 INFO - mozversion == 1.4 15:46:40 INFO - psutil == 3.1.1 15:46:40 INFO - requests == 1.2.3 15:46:40 INFO - wsgiref == 0.1.2 15:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:40 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:40 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 15:46:40 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 15:46:40 INFO - Using env: {'DISPLAY': ':0', 15:46:40 INFO - 'HOME': '/home/cltbld', 15:46:40 INFO - 'LANG': 'en_US.UTF-8', 15:46:40 INFO - 'LANGUAGE': 'en_US:en', 15:46:40 INFO - 'LOGNAME': 'cltbld', 15:46:40 INFO - 'MAIL': '/var/mail/cltbld', 15:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:40 INFO - 'MOZ_NO_REMOTE': '1', 15:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:40 INFO - 'NO_EM_RESTART': '1', 15:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:40 INFO - 'PWD': '/builds/slave/test', 15:46:40 INFO - 'SHELL': '/bin/bash', 15:46:40 INFO - 'SHLVL': '1', 15:46:40 INFO - 'TERM': 'linux', 15:46:40 INFO - 'TMOUT': '86400', 15:46:40 INFO - 'USER': 'cltbld', 15:46:40 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:40 INFO - '_': '/tools/buildbot/bin/python'} 15:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 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)) 15:46:41 INFO - Cleaning up... 15:46:41 INFO - Return code: 0 15:46:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:46:41 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')]} 15:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:41 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')]} 15:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:41 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 0x17531f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac4c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', '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 15:46:41 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 15:46:41 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 15:46:41 INFO - Using env: {'DISPLAY': ':0', 15:46:41 INFO - 'HOME': '/home/cltbld', 15:46:41 INFO - 'LANG': 'en_US.UTF-8', 15:46:41 INFO - 'LANGUAGE': 'en_US:en', 15:46:41 INFO - 'LOGNAME': 'cltbld', 15:46:41 INFO - 'MAIL': '/var/mail/cltbld', 15:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:41 INFO - 'MOZ_NO_REMOTE': '1', 15:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:41 INFO - 'NO_EM_RESTART': '1', 15:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:41 INFO - 'PWD': '/builds/slave/test', 15:46:41 INFO - 'SHELL': '/bin/bash', 15:46:41 INFO - 'SHLVL': '1', 15:46:41 INFO - 'TERM': 'linux', 15:46:41 INFO - 'TMOUT': '86400', 15:46:41 INFO - 'USER': 'cltbld', 15:46:41 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:41 INFO - '_': '/tools/buildbot/bin/python'} 15:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:46:41 INFO - Cleaning up... 15:46:41 INFO - Return code: 0 15:46:41 INFO - Running post-action listener: _resource_record_post_action 15:46:41 INFO - Running post-action listener: _start_resource_monitoring 15:46:41 INFO - Starting resource monitoring. 15:46:41 INFO - [mozharness: 2017-01-06 23:46:41.310632Z] Finished create-virtualenv step (success) 15:46:41 INFO - [mozharness: 2017-01-06 23:46:41.311097Z] Running install step. 15:46:41 INFO - Running pre-action listener: _resource_record_pre_action 15:46:41 INFO - Running main action method: install 15:46:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:41 INFO - Reading from file tmpfile_stdout 15:46:41 INFO - Detecting whether we're running mozinstall >=1.0... 15:46:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:46:41 INFO - Reading from file tmpfile_stdout 15:46:41 INFO - Output received: 15:46:41 INFO - Usage: mozinstall [options] installer 15:46:41 INFO - Options: 15:46:41 INFO - -h, --help show this help message and exit 15:46:41 INFO - -d DEST, --destination=DEST 15:46:41 INFO - Directory to install application into. [default: 15:46:41 INFO - "/builds/slave/test"] 15:46:41 INFO - --app=APP Application being installed. [default: firefox] 15:46:41 INFO - mkdir: /builds/slave/test/build/application 15:46:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:46:57 INFO - Reading from file tmpfile_stdout 15:46:57 INFO - Output received: 15:46:57 INFO - /builds/slave/test/build/application/firefox/firefox 15:46:57 INFO - Running post-action listener: _resource_record_post_action 15:46:57 INFO - [mozharness: 2017-01-06 23:46:57.684502Z] Finished install step (success) 15:46:57 INFO - [mozharness: 2017-01-06 23:46:57.684777Z] Running run-tests step. 15:46:57 INFO - Running pre-action listener: _resource_record_pre_action 15:46:57 INFO - Running main action method: run_tests 15:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:46:57 INFO - Python 2.7.3 15:46:57 INFO - Return code: 0 15:46:57 INFO - grabbing minidump binary from tooltool 15:46:57 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')]} 15:46:57 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 0x1ac23f0>, '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 0x1ac2cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:46:57 INFO - Return code: 0 15:46:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:46:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:46:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:46:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:46:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:46:57 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/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 15:46:57 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/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 15:46:57 INFO - Using env: {'DISPLAY': ':0', 15:46:57 INFO - 'HOME': '/home/cltbld', 15:46:57 INFO - 'LANG': 'en_US.UTF-8', 15:46:57 INFO - 'LANGUAGE': 'en_US:en', 15:46:57 INFO - 'LOGNAME': 'cltbld', 15:46:57 INFO - 'MAIL': '/var/mail/cltbld', 15:46:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:57 INFO - 'MOZ_NO_REMOTE': '1', 15:46:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:57 INFO - 'NO_EM_RESTART': '1', 15:46:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:57 INFO - 'PWD': '/builds/slave/test', 15:46:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:57 INFO - 'SHELL': '/bin/bash', 15:46:57 INFO - 'SHLVL': '1', 15:46:57 INFO - 'TERM': 'linux', 15:46:57 INFO - 'TMOUT': '86400', 15:46:57 INFO - 'USER': 'cltbld', 15:46:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406', 15:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:57 INFO - '_': '/tools/buildbot/bin/python'} 15:46:57 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/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 15:46:57 INFO - mozversion application_buildid: 20170106172842 15:46:57 INFO - mozversion application_changeset: da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e 15:46:57 INFO - mozversion application_display_name: Nightly 15:46:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:46:57 INFO - mozversion application_name: Firefox 15:46:57 INFO - mozversion application_remotingname: firefox 15:46:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 15:46:57 INFO - mozversion application_vendor: Mozilla 15:46:57 INFO - mozversion application_version: 53.0a1 15:46:57 INFO - mozversion platform_buildid: 20170106172842 15:46:57 INFO - mozversion platform_changeset: da4d2276cd297b3f74e59c5aa4a9ec81c0020d1e 15:46:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 15:46:57 INFO - mozversion platform_version: 53.0a1 15:46:57 INFO - using testdate: 1483746417 15:46:57 INFO - actual date: 1483746417 15:46:57 INFO - starting webserver on 'localhost:34176' 15:46:57 INFO - SUITE-START | Running 2 tests 15:46:57 INFO - TEST-START | dromaeo_css 15:46:57 INFO - operating with platform_type : linux_ 15:46:57 INFO - Initialising browser for dromaeo_css test... 15:46:57 INFO - TEST-INFO | started process 19686 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvVeiHx/profile http://localhost:34176/getInfo.html) 15:46:58 INFO - PROCESS | 19686 | 1483746418480 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 15:47:01 INFO - PROCESS | 19686 | __metrics Screen width/height:1600/1200 15:47:01 INFO - PROCESS | 19686 | colorDepth:24 15:47:01 INFO - PROCESS | 19686 | Browser inner width/height: 1024/697 15:47:01 INFO - PROCESS | 19686 | __metrics 15:47:02 INFO - PROCESS | 19686 | 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" 15:47:02 INFO - PROCESS | 19686 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:47:03 INFO - PROCESS | 19686 | console.error: PushService: 15:47:03 INFO - PROCESS | 19686 | stateChangeProcessEnqueue: Error transitioning state 15:47:03 INFO - PROCESS | 19686 | UnknownError 15:47:03 INFO - TEST-INFO | 19686: exit 0 15:47:03 INFO - Browser initialized. 15:47:03 INFO - Running cycle 1/1 for dromaeo_css test... 15:47:03 INFO - TEST-INFO | started process 19767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvVeiHx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:15 INFO - PROCESS | 19767 | RSS: Main: 151601152 15:47:15 INFO - PROCESS | 19767 | 15:50:20 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:34176/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34176/tests/dromaeo/cssquery-ext.html) 15:50:21 INFO - PROCESS | 19767 | RSS: Main: 255348736 15:50:21 INFO - PROCESS | 19767 | 15:52:04 INFO - PROCESS | 19767 | ************************* 15:52:04 INFO - PROCESS | 19767 | A coding exception was thrown and uncaught in a Task. 15:52:04 INFO - PROCESS | 19767 | 15:52:04 INFO - PROCESS | 19767 | Full message: ReferenceError: fetch is not defined 15:52:04 INFO - PROCESS | 19767 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 15:52:04 INFO - PROCESS | 19767 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 15:52:04 INFO - PROCESS | 19767 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 15:52:04 INFO - PROCESS | 19767 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:52:04 INFO - PROCESS | 19767 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:52:04 INFO - PROCESS | 19767 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:52:04 INFO - PROCESS | 19767 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 15:52:04 INFO - PROCESS | 19767 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:52:04 INFO - PROCESS | 19767 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:52:04 INFO - PROCESS | 19767 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:52:04 INFO - PROCESS | 19767 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 15:52:04 INFO - PROCESS | 19767 | 15:52:04 INFO - PROCESS | 19767 | ************************* 15:53:24 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:34176/tests/dromaeo/cssquery-ext.html (next: http://localhost:34176/tests/dromaeo/cssquery-jquery.html) 15:53:24 INFO - PROCESS | 19767 | RSS: Main: 244879360 15:53:24 INFO - PROCESS | 19767 | 15:56:26 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:34176/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34176/tests/dromaeo/cssquery-mootools.html) 15:56:27 INFO - PROCESS | 19767 | RSS: Main: 241479680 15:56:27 INFO - PROCESS | 19767 | 15:59:29 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:34176/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34176/tests/dromaeo/cssquery-prototype.html) 15:59:30 INFO - PROCESS | 19767 | RSS: Main: 183721984 15:59:30 INFO - PROCESS | 19767 | 16:02:33 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:34176/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34176/tests/dromaeo/cssquery-yui.html) 16:02:34 INFO - PROCESS | 19767 | RSS: Main: 186605568 16:02:34 INFO - PROCESS | 19767 | 16:05:36 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:34176/tests/dromaeo/cssquery-yui.html (next: http://localhost:34176/tests/dromaeo/cssquery-dojo.html) 16:05:37 INFO - PROCESS | 19767 | RSS: Main: 264613888 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | __start_tp_report 16:05:37 INFO - PROCESS | 19767 | _x_x_mozilla_page_load 16:05:37 INFO - PROCESS | 19767 | _x_x_mozilla_page_load_details 16:05:37 INFO - PROCESS | 19767 | |i|pagename|runs| 16:05:37 INFO - PROCESS | 19767 | |0;dojo.html;2536.4546622476164;2554.272032470746;2566.3327534840946;2571.138668546037;2601.765841074303;4564.863054108382;4635.443746750395;4687.250039993602;4707.576318131368;4724.4094488188975;4702.623790096793;4712.670113092085;4728.0543891221805;4760.309755085511;4801.615870730342;4262.360645903108;4299.978500107495;4339.2406328892475;4368.825246990116;4443.666724995622;4017.758934463927;4339.39248505208;4381.539938306464;4415.757133357664;4444.555544445546;4273.017206042628;4367.061081867414;4384.386185933972;4430.224710675635;4441.866743997685;4295.914081718348;4305.246581848164;4336.392904993303;4337.089211265638;4354.368616550582;4324.913501729948;4330.870073897757;4337.219300525904;4350.695451318409;4384.298512238027;4726.83456079039;4731.787069581843;4738.64460165489;4740.502247264057;4742.596879265267;4947.158982972904;4977.129002424561;4978.452370239271;5044.47033061527;5073.873153171164;5002.074616196015;5010.72441015744;5053.797848086072;5064.82273120439;5128.666636668605;4747.525247475225;4750.144973904661;4750.168720473903;4752.572268495817;4799.9760001199775;5159.716215608102;5163.173892177234;5165.302684137593;5170.043541944677;5202.219667049897;4847.684900481458;4871.756412179377;4914.459409464956;4932.7286999215385;4950.8514744557;4923;4995.725235112031;5030.346055012825;5033.194688849767;5083.923741143886;4901.852944411673;4917.213245880714;4966.329545511309;4977;5039.697618142924;4975;4983.800647974041;4983.8504844854715;4985.302057711923;5024.447310795809;4259.914801703949;4268.189044081677;4280.871573852789;4292.892677683083;4337.392765012901;3708.6291370863237;3711.2391959648207;3726.6273372662517;3730.403135498307;3748.381517049711;4335.067960388532;4338.934915976263;4340.131973605292;4367.847125350598;4374;4937.975310123498;4971.527704868032;4971.751412429364;5021.774020169101;5024.296598476216;4339.804708788049;4376.518582955872;4378.562143785596;4386.561343865588;4401.2517871961845;4597.655175861823;4604.447466303998;4613.700109492872;4615.076984603093;4659.347691323217;4597.540245975376;4735.526447355306;4736.50266722003;4738.905221895612;4780.689255198401;4924.8768780780765;4991.3511243538105;5067.771950262173;5075.365579302587;5105;4720.386349774644;4723.551262630044;4727.078219747116;4728.976355118202;4731.195696731633;4970.155073637563;4976.452590215146;4976.477469865757;4986.551210391082;5022.74886255693;4899.510048995072;4927.926081108858;4992.326035985241;4998.750062496933;5025.924611130763;4877.879131143424;4910.410750709938;4911.63162762787;4912.361393018885;4938.901221975469;4797.064572408347;4804.95195048045;4840.467548569725;4863.173260545786;4949.752512374439;4809.2305231162845;4827.565519103297;4829.179039563212;4856.4415092263425;4890.462049174659;4916.7541622919425;4920.8769780755765;4937.9259311109645;4946.62900282473;4975.726335051607;4764.642651801199;4821.228603423436;4824.372831531879;4844.0554091951735;4873.098476781806 16:05:37 INFO - PROCESS | 19767 | |1;ext.html;535.1918602909608;543.9700816455094;546.7785546853523;572.5076434266533;574.9708022639476;30019.399612007775;39989.4001589976;42452;42466.57533424665;46183.76908115455;42839.57160428395;43184.56815431845;43286.567134328645;43318.133637327315;43395.78302108493;7532.209118042609;7717.72987945422;7877;7882.054153501588;7886.6451009704415;7432.14530329011;7709.344705700022;7712.575808330567;9979.401235925867;10261.640842570512;10180.847287290697;10196.1843052556;10228.386296822177;10253.333533320347;10261.846072308921;10116.494175291205;10150.9492452538;10158.238132140083;10174.135198508136;10180.949095254551;9925;10003.799924001552;10004.749881252954;10006.249531285182;10030.89969100307;39910.60089399127;40244;40308.193836123406;40325.1934961302;40346.59653403458;3674.8346324415465;3737.8131093445218;3762.965184574264;3777.773333599965;3811.7331786774935;28868.111165789272;28992;29210.41579168426;29625.40749185026;29890.40219195626;3521.1725244567506;3535.5580552430947;3560.2345495718546;3643.526341575605;3763.3037887990536;230083;231462;231588;231669;231892;54614.45385546094;54834.72582637061;54850.17724734132;55019;55040.72479637656;58394;58436.70781646064;58488;58493.41506584965;58540;14751.631209219855;14995.400183992519;15017.699646007019;15018.474353397798;15027.699446011236;11269.436528173559;11350.14873884462;11351;11352.161959510122;11356.26184298018;15232;15552.533423997298;15600.765988510182;16084.195790210442;16111.194440278174;5679.06911650041;6247.75008999644;6358.265945643325;6450.838729031811;6568;785.8231897823058;789.1831953927657;790.4703848421572;792.8810678398171;817.3093735793245;27374;28220;28271.57592636112;28698;28722.569161462594;985.8324084905584;986.5412583148727;1010.0656892374524;1013.5591017907086;1049.968500944973;14191.361388737532;15226.162561059027;15382.781772674956;15391.846081539265;15399;343297;345907;346662;349264;349991;50874.49125508771;51152.48847511477;51198;51216.74391628018;51388.22917656238;1211.7213041000618;1251.3180316727405;1258.5217617305548;1262.4571434283162;1268.9111762176558;25621.743782561934;26497;27112.72887271102;27228.72771272262;27272.863635681693;42810.571894280656;43321.13357732891;43327.78336108426;43391.56608433875;43500.34749478697;224379;226456;227286;228194;228640;36217.29175207206;36245.81877090598;36624.45063323999;36927.261454771295;37003.44494832526;39437.80281098576;39561.406578901915;39830;40310.39534407045;40828;34417.31165376728;34451.82774086113;34533.13667158341;34938.12654683551;35068.64931350654;33923;34057.829710851285;34502.65497344994;34547.30905381828;34891.30217395689;34035.48946765751;34271.97184084482;34332.31335373328;34662;34849.47725784065;29076.27309317284;31628.841855790575;31687.841560792047;32046.6795332053;32055.358892822478;23069.307920762403;23413.648795267745;23688;23946.144895400652;24087.397815054763 16:05:37 INFO - PROCESS | 19767 | |2;jquery.html;361.3495707726094;366.6736604422063;378.9276347935343;387.93211188042096;410.08960108559006;13783.586492405231;17299.913500432496;18185.181666824992;18957.24171033157;19177.137028833702;18187.636247275048;18489.151084891506;18731.250749969986;18793.342233021845;18796;5056.140456122468;5300.045991721508;5302.3637163540425;5306.867328316804;5316.096263635172;3948.466956960817;4147.937780933288;5175.844724658266;5282;5285.550728188108;4145;6079.726412311435;6105.511559075263;6212.502999760009;6220.284667263255;5276.657017293864;5462.071447853875;6174.814755557318;6177.3822617738315;6188.659623720693;5469.917951230735;5485.9725701371235;5488.1493368527945;5497.39528651852;5505.614606977513;12767.74464510714;18176.363827266116;18917.33789317381;22232.555348892933;22380.88809555958;2212.5021870079263;2228.7771222877745;2231.252530402317;2235.944101397462;2323.0939933425975;9126.726198214064;9174.403663761834;9278.675246366412;9734.075262850018;9751.463669498175;1765.841074303306;1767.7701898753207;1774.4233124234547;1775.5916139288036;1819.245013319471;73973.12322521278;80069.59965200137;80818;80865.59567202185;81309.18690813075;9895.554700038516;9932.553035113437;10113.44376059309;10618.469076546142;10897.346159230472;14665.92667036658;14689.045212061395;14693.485727999469;14705.117692938458;14705.632359190891;7291.598962057032;7525.2098529654695;7537;7729.1884352142215;7736.110347310131;7112.253213412517;7136.0723105995885;7299.270072992658;7303.342699157101;7320.7071717131785;4405.625521830681;4473.575010374009;4519.977400112978;4594.632429405639;4614.377059097046;1780.2433965564583;1890.4801179675424;1899.4586542835225;1903.8889746795708;1904.5714714189473;814.8573999550055;838.7609531283555;854.9487030778173;859.1408591408591;871.5206636349982;17427.215775290144;17683.646327073642;18318.54203644893;18674.439766807016;18905.90547047256;837.7528629054443;840.2018082821344;850.2815121222557;850.7447765670274;860.0453496618749;9671.613135474503;10357.171426285879;10484.790304193873;10488.73778155537;10509.159267258601;497931.04137917765;499360;499383;506795;512007;83974.58012709898;85995;86198;87078.56460717655;87326.56336718403;881.9118088191258;898.1377877237794;908.2506931781174;922.5064590444081;923.732117685877;15281.235938202823;15365.539033829004;16071.517854464384;16307.266173022004;16314.836851631331;38289.617103828605;38296.61703383042;38304.42543361796;39144.80427597844;39208.607913920496;51123.48876511187;52085;52803;53962.73018634882;55278.17082743842;5367.275417818701;6357.650329231937;6572.868542629216;7907.2488113629115;7923.20767923203;11537.711557210801;12601.18092323996;12859.357032148167;13052.282124483245;13205.471781128646;10260.17918566498;10380.532876020447;10554.683359499228;10933.086709825908;11134.610288639858;11066.225364224749;11101.988824369546;11143.108551315712;11398;11753.588624398106;10405.427701476188;10551.525181366704;10681.732956676145;10864.45677716127;11085.445727713743;7991.72028978983;8003.6398362075015;8093.595320233847;8189.385796065199;8240.340772738287;9447.574859131493;11007.229493935347;11049.116070714163;11188.992990630883;11199.496022679163 16:05:37 INFO - PROCESS | 19767 | |3;mootools.html;1322.4577923051545;1332.0941759603468;1345.4369426243004;1346.0981142634434;1348.0496250143647;6594.703238354279;6818.352256535633;6889.621070841108;6912.205096413914;6934.479914006451;6148.569600127993;6291.213598300213;6733.39399454049;6824.0446337512785;6852;2100.548382097849;2138.8182004529635;2141.389703934371;2145.2384403536753;2147.624165770984;2065.834733221346;2103.4215590712524;2115.947101322464;2122.8089471947524;2123.2462475821094;1846.0861873372698;1898.3450709505198;2068.2037415595028;2112.207922029239;2131.957360852782;2083.656196727538;2086.69742887282;2091.4248581640018;2099.590579836932;2101.495641046153;2046.3144846476448;2058.6706127019756;2059.824914882237;2067.6071546406283;2096.5178009058004;6994.965025174893;7014.123234595676;7036.859262814766;7063.1524217094275;7074.823129421754;4255.446791917062;4377.649788016951;4381.408509851161;4382.912341753147;4384.890377740582;2739.794515411352;2878.4674835155356;2898.536244192441;2900.0284904556997;2948.056621880999;1109.8945600167972;1150.9424528773527;1179.109772122051;1184;1246.214884622691;9143.954280228556;9150.679726209617;9162.587683554257;9178.954105229497;9273.768155796091;3704.24063067076;3960.3663413853646;3963.801809909493;4046.9595304046584;4052.696047796427;7852.764417067498;7857.764267071882;7858.882116768253;7945.1657575953695;7980.800479988046;4354.303311470213;4392.736435813861;4393.231184542692;4422.911541769211;4428.689991700615;3628.727845411605;3739.495168152265;3909.706771992112;4067.7762723049923;4075.694322925733;3356.47974563943;3362.36115138123;3511.648835116468;3524.947125793115;3530.382183117944;1133.6145710458318;1138.8576427946507;1144.4449442020614;1147.839302497651;1157.7279339355246;664.299164381582;676.749602647014;757.7044952468542;820.2986446588201;842.9578521073922;4453.554644535585;4457.7771111444945;4563.178627847019;4692.859214223578;4812.374391329105;865.312076898867;869.8651708985118;876.6230520875957;884.9159329863653;928.396542247531;3197.2646291352653;3318.9336213276083;3382.797032178077;3387.542681737983;3418.2908545727137;8131.4714543555665;8285.751427457186;8366.623501942428;8382.329413646894;8401.411901166859;8300.916990830136;8335.791605209797;8353.122922093215;8357.958210209032;8370.539620320818;6365.14070600463;6394.360563943568;6552.737890484423;6639.468842492685;6653.800385988428;4723.488483685188;4757.524247575215;4764.571188593043;4826.107170173529;4893.168161412497;7657.425693072931;7661.233876612294;7683.001209842685;7702.037245344332;7762.805929851573;7310.561366318038;7596.696132154652;7598.772036838904;7810.648520816463;7819.921800782147;5581.944180558305;5721;6175.042868355412;6215.564910456224;6291.370862913672;6345.206849143857;6489.545731798728;6519.022146678036;6666.366695163952;6678.699458524476;5788.363280039276;5834.38738932423;6060.090986352083;6147.66187859672;6222.191115155001;5770.884582308414;5799.652020878592;5814.883702326015;5836.562257830595;5954.76180952757;5073.137566613611;5213.113770658922;5249.842504724865;5304.814331498379;5331.173668081454;4108.630223279919;4146.647534959562;4254.574542545721;4268.445102136827;4333.090051089306;4217.325227963511;4305.117450922616;4314.158739045856;4346;4414.492333381574 16:05:37 INFO - PROCESS | 19767 | |4;prototype.html;352.7231123567998;354.21718003212897;354.2914171656687;354.60284481380853;358.25125487730656;4836.806527738886;4840.467548569654;4845.394325709286;4863.537963893434;4891.730954797485;4787.6169906407495;4810.831620893269;4811.15804734171;4816.807327706888;4823.20417131173;3840.519935008124;3917.941230881539;3933.645971862532;3938.468306778577;3940.901477463072;3915.686745060388;3925.60743925608;3927.0771368728324;3927.3127202739547;3940.2710498557717;3882.1265215326607;3885.417187421881;3890.4164375343785;3893.493845800042;3898.200868821885;3781.8865434037025;3882.5729169791302;3907.2185562887535;3910.4134379842967;3915.5497117831246;3482.89551313461;3499.737519686034;3514.38498262803;3535.1338921964025;3540.6105328413855;4696.812127514895;4698.533270033226;4745.644076694227;4752.550472106006;4769.928451073237;4449.199144154051;4459.197344477992;4465.196264672358;4471.955280447187;4483.663725220589;4867.415910090812;5087.720175390352;5120.257562653432;5130.97434512825;5162.483751624845;3866.7486613370043;3877.3990031545154;3885.8834234972996;3893.2213557288655;3897.6881849451975;5049.697018178885;5325.109586096919;5333.706646134461;5360.705161216131;5370.516653501204;3794.9563869935437;3798.4492248624347;3890.6498415142773;3918.8236529356245;3937.9170728049444;3939.4484772131923;3951.7431366961055;3956.9406458902563;3959.8318496043735;3995.700322475826;4180.331147016463;4368.716033457815;4402.669799765031;4429.534898835575;4432.8696182473905;4967.254911763264;4982.402111746614;4982.77577509013;4985.8781774100535;4997.625178111656;1312.6390242683337;1325.1651459580596;1330.4279159961116;1330.5542643214437;1331.740310639416;936.3241817989373;952.4380615436787;953.2231231546267;978.5792109393029;994.8259054665549;4269.573042695705;4285.742855428622;4295;4331.610155085994;4331.6318112960125;4980.862056904127;4996.625253106032;5010.824621138316;5014.222795466709;5040.571551418097;4251.872245054186;4298.774849167972;4314.460692413449;4330.242207613655;4351.3472979053395;2583.9354016149746;2676.892924283007;2687.865606719656;2692.0039585353543;2702.9594556081674;4760.714357138583;4760.880977975578;4766.356541866873;4769.189237829577;4772.689775164603;4891.461939186686;5173.301604283449;5181.290174242491;5184.407796101949;5193.169092945111;4725.40932383452;4733.242681170997;4737.431508219036;4738.668293219511;4740.5733483986605;5014.921791814777;5032.194848824171;5032.27032080339;5063.835317876836;5077.796888124433;4817.927731083966;4968.726720030433;4982.75086245679;4988.575971042357;5000.549950504326;4659.184642687584;4766.3327134200545;4858.58702010333;4867.926981095357;4875.756212189447;3615.74689771724;3703.666669999713;3710.2023065041144;3714.7213958953726;3729.645683660048;5215.843524694265;5224.2947202127225;5247.606429517725;5248.921266181087;5255.763490642854;4939.308496810521;5052.343195384577;5100.38795344561;5104.489551044866;5110.795568177231;5042.243663450512;5046.268291097844;5048.419431765394;5054.4187418447345;5064.974675126601;4993.201087825931;4999.800007999639;5016.316270795107;5051.12086110928;5075.2387141929;4977.502249774993;5032.496750324938;5110.540051395392;5123.692578445305;5149.176131818891;4962.33008543842;4965.4289756678445;4976.805566664047;4976.825811096594;4989.775460104376 16:05:37 INFO - PROCESS | 19767 | |5;yui.html;598.9760409583616;624.7598516943868;707.1584814071255;724.565260843494;736.4771012581069;12350.382480875955;13480.325983700825;13496.392662330194;13544.39050242739;13545.864541354596;13467.93266033671;13550.322483875816;13600.863991360084;13658.863411365883;13677.931610341959;655.7606473637112;676.0569006236298;774.4540099230029;792.6591565626778;810.1007881251805;793.3851265269404;815.4047545291941;816.0330008939396;817.1787353709514;817.652497688483;863.2662237098478;866.0819531296845;868.2663149638545;869.1048220333067;870.0081906626451;686.1114856261154;709.3367701199395;800.3637108498757;858.716623514242;858.9441686290403;615.7690865925279;626.8620903401245;627.244170774216;627.5575719118011;627.8618703885138;13921.164730116225;13954.93022534891;13955.441782328797;13978.091424057506;13993.650158746012;11716.53133874644;12008.879911200864;12496.75006499874;12563.93718031404;12658.493660253578;12154.209976351598;12428.316442595653;12465.439055242536;12778.872211277861;12829.61511154667;4059.9188016239805;4161.0845613964875;4176.0603864130635;4185.707000509966;4205.516365617932;63093.05360419597;65017.67491162561;65215.02177467342;65768;66777.66611166962;10046.346987445793;10072.697819065294;10152.796944061225;10180.23648226386;10399.53202105907;17491.737623935653;17517.124143793015;17517.737233941247;17607.647847042987;17667.11664416774;6261.7182226799905;6271.560990730696;6319.115323854664;6495.805125846232;6518.543701940914;3434.587849458081;3519.296140771805;3556.928861422757;3562.8040457774555;3597.640235976434;2776.9583456248174;2849.33040735427;2869.096234686109;2875.9856200718864;2882.3514709190263;457.35512926773754;465.1952122827527;469.94360676718327;473.52410827118524;473.5548584330718;500.21216583880533;522.0811371985333;524.4126578232327;562.4436934134369;577.3331801768963;7836.182521206587;8202.958985205036;8336.24973752353;8404.663813447516;8605.440646357967;519.870032491877;521.5306224398072;533.786485405833;535.9303290572278;551.6331639459795;3836.405357169643;3853.9807300963876;3881.6700580450415;3900.3813417432016;4028.1339512004474;62129.37870621326;62282.37717622856;63964.36035639584;64445;65923.34076659172;57828.79540841345;58739.70630146822;59096.70451647714;59708;59928;53643.73178134162;53822;53845.461545384045;54394.72802635962;54523;7286.234945330876;7483.669766953653;7673.34776544;7845.646945887334;7921.801954951173;36004.639953600126;36729.26541469102;36989.44515832211;37519.624803752704;37678.24643507169;37274.813625931696;44836.77581612071;45904;45945.54054459546;47154.05691886075;8708.69519566812;8769.254613357682;8861.468311901306;8868.201861832466;8892;9343.579538920902;9450.291228157777;9542.85685714701;9562.521873906138;9682.177014953808;9352.3453358267;9372.67195648149;9403.717888463358;9471.857922131312;9479.810403792024;8875.816341791611;8921.553922303729;8936.597853096757;9022.954885225532;9037.277017838689;9377.343585948918;9416.246700264099;9420.717378478657;9438.480883551196;9446.480443575669;7231.63841807918;7466.962665186639;7479;7499.325060744559;7539.547627142389;6989.266127056586;7006;7147.3210045045635;7257.419406447577;7353.338199562065 16:05:37 INFO - PROCESS | 19767 | __end_tp_report 16:05:37 INFO - PROCESS | 19767 | __start_cc_report 16:05:37 INFO - PROCESS | 19767 | _x_x_mozilla_cycle_collect,1156 16:05:37 INFO - PROCESS | 19767 | __end_cc_report 16:05:37 INFO - PROCESS | 19767 | __startTimestamp1483747537231__endTimestamp 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | ------- Summary: start ------- 16:05:37 INFO - PROCESS | 19767 | Number of tests: 6 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | [#0] dojo.html Cycles:165 Average:4649.15 Median:4764.64 stddev:487.00 (10.2%) stddev-sans-first:459.43 16:05:37 INFO - PROCESS | 19767 | Values: 2536.5 2554.3 2566.3 2571.1 2601.8 4564.9 4635.4 4687.3 4707.6 4724.4 4702.6 4712.7 4728.1 4760.3 4801.6 4262.4 4300.0 4339.2 4368.8 4443.7 4017.8 4339.4 4381.5 4415.8 4444.6 4273.0 4367.1 4384.4 4430.2 4441.9 4295.9 4305.2 4336.4 4337.1 4354.4 4324.9 4330.9 4337.2 4350.7 4384.3 4726.8 4731.8 4738.6 4740.5 4742.6 4947.2 4977.1 4978.5 5044.5 5073.9 5002.1 5010.7 5053.8 5064.8 5128.7 4747.5 4750.1 4750.2 4752.6 4800.0 5159.7 5163.2 5165.3 5170.0 5202.2 4847.7 4871.8 4914.5 4932.7 4950.9 4923.0 4995.7 5030.3 5033.2 5083.9 4901.9 4917.2 4966.3 4977.0 5039.7 4975.0 4983.8 4983.9 4985.3 5024.4 4259.9 4268.2 4280.9 4292.9 4337.4 3708.6 3711.2 3726.6 3730.4 3748.4 4335.1 4338.9 4340.1 4367.8 4374.0 4938.0 4971.5 4971.8 5021.8 5024.3 4339.8 4376.5 4378.6 4386.6 4401.3 4597.7 4604.4 4613.7 4615.1 4659.3 4597.5 4735.5 4736.5 4738.9 4780.7 4924.9 4991.4 5067.8 5075.4 5105.0 4720.4 4723.6 4727.1 4729.0 4731.2 4970.2 4976.5 4976.5 4986.6 5022.7 4899.5 4927.9 4992.3 4998.8 5025.9 4877.9 4910.4 4911.6 4912.4 4938.9 4797.1 4805.0 4840.5 4863.2 4949.8 4809.2 4827.6 4829.2 4856.4 4890.5 4916.8 4920.9 4937.9 4946.6 4975.7 4764.6 4821.2 4824.4 4844.1 4873.1 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | [#1] ext.html Cycles:180 Average:43813.18 Median:190284.50 stddev:71728.64 (37.7%) stddev-sans-first:71856.25 16:05:37 INFO - PROCESS | 19767 | Values: 535.2 544.0 546.8 572.5 575.0 30019.4 39989.4 42452.0 42466.6 46183.8 42839.6 43184.6 43286.6 43318.1 43395.8 7532.2 7717.7 7877.0 7882.1 7886.6 7432.1 7709.3 7712.6 9979.4 10261.6 10180.8 10196.2 10228.4 10253.3 10261.8 10116.5 10150.9 10158.2 10174.1 10180.9 9925.0 10003.8 10004.7 10006.2 10030.9 39910.6 40244.0 40308.2 40325.2 40346.6 3674.8 3737.8 3763.0 3777.8 3811.7 28868.1 28992.0 29210.4 29625.4 29890.4 3521.2 3535.6 3560.2 3643.5 3763.3 230083.0 231462.0 231588.0 231669.0 231892.0 54614.5 54834.7 54850.2 55019.0 55040.7 58394.0 58436.7 58488.0 58493.4 58540.0 14751.6 14995.4 15017.7 15018.5 15027.7 11269.4 11350.1 11351.0 11352.2 11356.3 15232.0 15552.5 15600.8 16084.2 16111.2 5679.1 6247.8 6358.3 6450.8 6568.0 785.8 789.2 790.5 792.9 817.3 27374.0 28220.0 28271.6 28698.0 28722.6 985.8 986.5 1010.1 1013.6 1050.0 14191.4 15226.2 15382.8 15391.8 15399.0 343297.0 345907.0 346662.0 349264.0 349991.0 50874.5 51152.5 51198.0 51216.7 51388.2 1211.7 1251.3 1258.5 1262.5 1268.9 25621.7 26497.0 27112.7 27228.7 27272.9 42810.6 43321.1 43327.8 43391.6 43500.3 224379.0 226456.0 227286.0 228194.0 228640.0 36217.3 36245.8 36624.5 36927.3 37003.4 39437.8 39561.4 39830.0 40310.4 40828.0 34417.3 34451.8 34533.1 34938.1 35068.6 33923.0 34057.8 34502.7 34547.3 34891.3 34035.5 34272.0 34332.3 34662.0 34849.5 29076.3 31628.8 31687.8 32046.7 32055.4 23069.3 23413.6 23688.0 23946.1 24087.4 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | [#2] jquery.html Cycles:180 Average:28344.72 Median:4496.78 stddev:82796.16 (1841.2%) stddev-sans-first:83001.77 16:05:37 INFO - PROCESS | 19767 | Values: 361.3 366.7 378.9 387.9 410.1 13783.6 17299.9 18185.2 18957.2 19177.1 18187.6 18489.2 18731.3 18793.3 18796.0 5056.1 5300.0 5302.4 5306.9 5316.1 3948.5 4147.9 5175.8 5282.0 5285.6 4145.0 6079.7 6105.5 6212.5 6220.3 5276.7 5462.1 6174.8 6177.4 6188.7 5469.9 5486.0 5488.1 5497.4 5505.6 12767.7 18176.4 18917.3 22232.6 22380.9 2212.5 2228.8 2231.3 2235.9 2323.1 9126.7 9174.4 9278.7 9734.1 9751.5 1765.8 1767.8 1774.4 1775.6 1819.2 73973.1 80069.6 80818.0 80865.6 81309.2 9895.6 9932.6 10113.4 10618.5 10897.3 14665.9 14689.0 14693.5 14705.1 14705.6 7291.6 7525.2 7537.0 7729.2 7736.1 7112.3 7136.1 7299.3 7303.3 7320.7 4405.6 4473.6 4520.0 4594.6 4614.4 1780.2 1890.5 1899.5 1903.9 1904.6 814.9 838.8 854.9 859.1 871.5 17427.2 17683.6 18318.5 18674.4 18905.9 837.8 840.2 850.3 850.7 860.0 9671.6 10357.2 10484.8 10488.7 10509.2 497931.0 499360.0 499383.0 506795.0 512007.0 83974.6 85995.0 86198.0 87078.6 87326.6 881.9 898.1 908.3 922.5 923.7 15281.2 15365.5 16071.5 16307.3 16314.8 38289.6 38296.6 38304.4 39144.8 39208.6 51123.5 52085.0 52803.0 53962.7 55278.2 5367.3 6357.7 6572.9 7907.2 7923.2 11537.7 12601.2 12859.4 13052.3 13205.5 10260.2 10380.5 10554.7 10933.1 11134.6 11066.2 11102.0 11143.1 11398.0 11753.6 10405.4 10551.5 10681.7 10864.5 11085.4 7991.7 8003.6 8093.6 8189.4 8240.3 9447.6 11007.2 11049.1 11189.0 11199.5 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | [#3] mootools.html Cycles:180 Average:4597.93 Median:4761.05 stddev:2414.81 (50.7%) stddev-sans-first:2409.03 16:05:37 INFO - PROCESS | 19767 | Values: 1322.5 1332.1 1345.4 1346.1 1348.0 6594.7 6818.4 6889.6 6912.2 6934.5 6148.6 6291.2 6733.4 6824.0 6852.0 2100.5 2138.8 2141.4 2145.2 2147.6 2065.8 2103.4 2115.9 2122.8 2123.2 1846.1 1898.3 2068.2 2112.2 2132.0 2083.7 2086.7 2091.4 2099.6 2101.5 2046.3 2058.7 2059.8 2067.6 2096.5 6995.0 7014.1 7036.9 7063.2 7074.8 4255.4 4377.6 4381.4 4382.9 4384.9 2739.8 2878.5 2898.5 2900.0 2948.1 1109.9 1150.9 1179.1 1184.0 1246.2 9144.0 9150.7 9162.6 9179.0 9273.8 3704.2 3960.4 3963.8 4047.0 4052.7 7852.8 7857.8 7858.9 7945.2 7980.8 4354.3 4392.7 4393.2 4422.9 4428.7 3628.7 3739.5 3909.7 4067.8 4075.7 3356.5 3362.4 3511.6 3524.9 3530.4 1133.6 1138.9 1144.4 1147.8 1157.7 664.3 676.7 757.7 820.3 843.0 4453.6 4457.8 4563.2 4692.9 4812.4 865.3 869.9 876.6 884.9 928.4 3197.3 3318.9 3382.8 3387.5 3418.3 8131.5 8285.8 8366.6 8382.3 8401.4 8300.9 8335.8 8353.1 8358.0 8370.5 6365.1 6394.4 6552.7 6639.5 6653.8 4723.5 4757.5 4764.6 4826.1 4893.2 7657.4 7661.2 7683.0 7702.0 7762.8 7310.6 7596.7 7598.8 7810.6 7819.9 5581.9 5721.0 6175.0 6215.6 6291.4 6345.2 6489.5 6519.0 6666.4 6678.7 5788.4 5834.4 6060.1 6147.7 6222.2 5770.9 5799.7 5814.9 5836.6 5954.8 5073.1 5213.1 5249.8 5304.8 5331.2 4108.6 4146.6 4254.6 4268.4 4333.1 4217.3 4305.1 4314.2 4346.0 4414.5 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | [#4] prototype.html Cycles:180 Average:4219.36 Median:4760.80 stddev:1174.85 (24.7%) stddev-sans-first:1141.74 16:05:37 INFO - PROCESS | 19767 | Values: 352.7 354.2 354.3 354.6 358.3 4836.8 4840.5 4845.4 4863.5 4891.7 4787.6 4810.8 4811.2 4816.8 4823.2 3840.5 3917.9 3933.6 3938.5 3940.9 3915.7 3925.6 3927.1 3927.3 3940.3 3882.1 3885.4 3890.4 3893.5 3898.2 3781.9 3882.6 3907.2 3910.4 3915.5 3482.9 3499.7 3514.4 3535.1 3540.6 4696.8 4698.5 4745.6 4752.6 4769.9 4449.2 4459.2 4465.2 4472.0 4483.7 4867.4 5087.7 5120.3 5131.0 5162.5 3866.7 3877.4 3885.9 3893.2 3897.7 5049.7 5325.1 5333.7 5360.7 5370.5 3795.0 3798.4 3890.6 3918.8 3937.9 3939.4 3951.7 3956.9 3959.8 3995.7 4180.3 4368.7 4402.7 4429.5 4432.9 4967.3 4982.4 4982.8 4985.9 4997.6 1312.6 1325.2 1330.4 1330.6 1331.7 936.3 952.4 953.2 978.6 994.8 4269.6 4285.7 4295.0 4331.6 4331.6 4980.9 4996.6 5010.8 5014.2 5040.6 4251.9 4298.8 4314.5 4330.2 4351.3 2583.9 2676.9 2687.9 2692.0 2703.0 4760.7 4760.9 4766.4 4769.2 4772.7 4891.5 5173.3 5181.3 5184.4 5193.2 4725.4 4733.2 4737.4 4738.7 4740.6 5014.9 5032.2 5032.3 5063.8 5077.8 4817.9 4968.7 4982.8 4988.6 5000.5 4659.2 4766.3 4858.6 4867.9 4875.8 3615.7 3703.7 3710.2 3714.7 3729.6 5215.8 5224.3 5247.6 5248.9 5255.8 4939.3 5052.3 5100.4 5104.5 5110.8 5042.2 5046.3 5048.4 5054.4 5065.0 4993.2 4999.8 5016.3 5051.1 5075.2 4977.5 5032.5 5110.5 5123.7 5149.2 4962.3 4965.4 4976.8 4976.8 4989.8 16:05:37 INFO - PROCESS | 19767 | 16:05:37 INFO - PROCESS | 19767 | [#5] yui.html Cycles:180 Average:14448.00 Median:30271.88 stddev:18745.92 (61.9%) stddev-sans-first:18769.66 16:05:37 INFO - PROCESS | 19767 | Values: 599.0 624.8 707.2 724.6 736.5 12350.4 13480.3 13496.4 13544.4 13545.9 13467.9 13550.3 13600.9 13658.9 13677.9 655.8 676.1 774.5 792.7 810.1 793.4 815.4 816.0 817.2 817.7 863.3 866.1 868.3 869.1 870.0 686.1 709.3 800.4 858.7 858.9 615.8 626.9 627.2 627.6 627.9 13921.2 13954.9 13955.4 13978.1 13993.7 11716.5 12008.9 12496.8 12563.9 12658.5 12154.2 12428.3 12465.4 12778.9 12829.6 4059.9 4161.1 4176.1 4185.7 4205.5 63093.1 65017.7 65215.0 65768.0 66777.7 10046.3 10072.7 10152.8 10180.2 10399.5 17491.7 17517.1 17517.7 17607.6 17667.1 6261.7 6271.6 6319.1 6495.8 6518.5 3434.6 3519.3 3556.9 3562.8 3597.6 2777.0 2849.3 2869.1 2876.0 2882.4 457.4 465.2 469.9 473.5 473.6 500.2 522.1 524.4 562.4 577.3 7836.2 8203.0 8336.2 8404.7 8605.4 519.9 521.5 533.8 535.9 551.6 3836.4 3854.0 3881.7 3900.4 4028.1 62129.4 62282.4 63964.4 64445.0 65923.3 57828.8 58739.7 59096.7 59708.0 59928.0 53643.7 53822.0 53845.5 54394.7 54523.0 7286.2 7483.7 7673.3 7845.6 7921.8 36004.6 36729.3 36989.4 37519.6 37678.2 37274.8 44836.8 45904.0 45945.5 47154.1 8708.7 8769.3 8861.5 8868.2 8892.0 9343.6 9450.3 9542.9 9562.5 9682.2 9352.3 9372.7 9403.7 9471.9 9479.8 8875.8 8921.6 8936.6 9023.0 9037.3 9377.3 9416.2 9420.7 9438.5 9446.5 7231.6 7467.0 7479.0 7499.3 7539.5 6989.3 7006.0 7147.3 7257.4 7353.3 16:05:37 INFO - PROCESS | 19767 | -------- Summary: end -------- 16:05:37 INFO - PROCESS | 19767 | 16:05:38 INFO - TEST-INFO | 19767: exit 0 16:05:38 INFO - TEST-OK | dromaeo_css | took 1120112ms 16:05:38 INFO - TEST-START | kraken 16:05:38 INFO - operating with platform_type : linux_ 16:05:38 INFO - Initialising browser for kraken test... 16:05:38 INFO - TEST-INFO | started process 20064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1TMoL7/profile http://localhost:34176/getInfo.html) 16:05:38 INFO - PROCESS | 20064 | 1483747538602 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 16:05:42 INFO - PROCESS | 20064 | __metrics Screen width/height:1600/1200 16:05:42 INFO - PROCESS | 20064 | colorDepth:24 16:05:42 INFO - PROCESS | 20064 | Browser inner width/height: 1024/697 16:05:42 INFO - PROCESS | 20064 | __metrics 16:05:42 INFO - PROCESS | 20064 | 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" 16:05:42 INFO - PROCESS | 20064 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:05:43 INFO - PROCESS | 20064 | console.error: PushService: 16:05:43 INFO - PROCESS | 20064 | stateChangeProcessEnqueue: Error transitioning state 16:05:43 INFO - PROCESS | 20064 | UnknownError 16:05:43 INFO - TEST-INFO | 20064: exit 0 16:05:43 INFO - Browser initialized. 16:05:43 INFO - Running cycle 1/1 for kraken test... 16:05:43 INFO - TEST-INFO | started process 20147 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1TMoL7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:55 INFO - PROCESS | 20147 | RSS: Main: 151584768 16:05:55 INFO - PROCESS | 20147 | 16:05:58 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34176/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:59 INFO - PROCESS | 20147 | RSS: Main: 205791232 16:05:59 INFO - PROCESS | 20147 | 16:06:02 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34176/tests/kraken/driver.html?testName=audio-dft) 16:06:02 INFO - PROCESS | 20147 | RSS: Main: 184934400 16:06:02 INFO - PROCESS | 20147 | 16:06:06 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34176/tests/kraken/driver.html?testName=audio-fft) 16:06:06 INFO - PROCESS | 20147 | RSS: Main: 268382208 16:06:06 INFO - PROCESS | 20147 | 16:06:08 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34176/tests/kraken/driver.html?testName=audio-oscillator) 16:06:09 INFO - PROCESS | 20147 | RSS: Main: 179306496 16:06:09 INFO - PROCESS | 20147 | 16:06:11 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34176/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:12 INFO - PROCESS | 20147 | RSS: Main: 182214656 16:06:12 INFO - PROCESS | 20147 | 16:06:15 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34176/tests/kraken/driver.html?testName=imaging-darkroom) 16:06:15 INFO - PROCESS | 20147 | RSS: Main: 221835264 16:06:15 INFO - PROCESS | 20147 | 16:06:19 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34176/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:20 INFO - PROCESS | 20147 | RSS: Main: 216096768 16:06:20 INFO - PROCESS | 20147 | 16:06:22 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34176/tests/kraken/driver.html?testName=json-parse-financial) 16:06:23 INFO - PROCESS | 20147 | RSS: Main: 217051136 16:06:23 INFO - PROCESS | 20147 | 16:06:25 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34176/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:25 INFO - PROCESS | 20147 | RSS: Main: 180580352 16:06:25 INFO - PROCESS | 20147 | 16:06:27 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:06:28 INFO - PROCESS | 20147 | RSS: Main: 187613184 16:06:28 INFO - PROCESS | 20147 | 16:06:30 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:06:31 INFO - PROCESS | 20147 | RSS: Main: 196972544 16:06:31 INFO - PROCESS | 20147 | 16:06:33 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:06:34 INFO - PROCESS | 20147 | RSS: Main: 206405632 16:06:34 INFO - PROCESS | 20147 | 16:06:37 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:06:38 INFO - PROCESS | 20147 | RSS: Main: 202833920 16:06:38 INFO - PROCESS | 20147 | 16:06:39 INFO - PROCESS | 20147 | Cycle 1(1): loaded http://localhost:34176/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34176/tests/kraken/driver.html?testName=ai-astar) 16:06:40 INFO - PROCESS | 20147 | RSS: Main: 253538304 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | __start_tp_report 16:06:40 INFO - PROCESS | 20147 | _x_x_mozilla_page_load 16:06:40 INFO - PROCESS | 20147 | _x_x_mozilla_page_load_details 16:06:40 INFO - PROCESS | 20147 | |i|pagename|runs| 16:06:40 INFO - PROCESS | 20147 | |0;ai-astar;107;115;116;116;115;114;113;114;114;114 16:06:40 INFO - PROCESS | 20147 | |1;audio-beat-detection;157;125;125;166;124;129;123;121;149;122 16:06:40 INFO - PROCESS | 20147 | |2;audio-dft;161;160;161;161;162;162;167;161;162;160 16:06:40 INFO - PROCESS | 20147 | |3;audio-fft;76;77;77;115;76;77;76;75;98;75 16:06:40 INFO - PROCESS | 20147 | |4;audio-oscillator;84;84;109;85;84;84;103;84;84;104 16:06:40 INFO - PROCESS | 20147 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;117 16:06:40 INFO - PROCESS | 20147 | |6;imaging-darkroom;149;149;149;149;148;149;149;148;148;149 16:06:40 INFO - PROCESS | 20147 | |7;imaging-desaturate;95;95;95;95;96;94;95;95;96;95 16:06:40 INFO - PROCESS | 20147 | |8;json-parse-financial;64;58;58;59;59;60;59;59;62;61 16:06:40 INFO - PROCESS | 20147 | |9;json-stringify-tinderbox;67;67;67;67;67;66;67;66;66;67 16:06:40 INFO - PROCESS | 20147 | |10;stanford-crypto-aes;83;85;83;90;83;84;84;83;82;83 16:06:40 INFO - PROCESS | 20147 | |11;stanford-crypto-ccm;133;134;132;134;131;129;132;133;175;131 16:06:40 INFO - PROCESS | 20147 | |12;stanford-crypto-pbkdf2;179;171;174;170;172;171;170;173;172;170 16:06:40 INFO - PROCESS | 20147 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;68;67;66;67;67 16:06:40 INFO - PROCESS | 20147 | __end_tp_report 16:06:40 INFO - PROCESS | 20147 | __start_cc_report 16:06:40 INFO - PROCESS | 20147 | _x_x_mozilla_cycle_collect,1843 16:06:40 INFO - PROCESS | 20147 | __end_cc_report 16:06:40 INFO - PROCESS | 20147 | __startTimestamp1483747600212__endTimestamp 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | ------- Summary: start ------- 16:06:40 INFO - PROCESS | 20147 | Number of tests: 14 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.50 stddev:2.57 (2.2%) stddev-sans-first:1.01 16:06:40 INFO - PROCESS | 20147 | Values: 107.0 115.0 116.0 116.0 115.0 114.0 113.0 114.0 114.0 114.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:127.00 stddev:16.66 (13.1%) stddev-sans-first:15.48 16:06:40 INFO - PROCESS | 20147 | Values: 157.0 125.0 125.0 166.0 124.0 129.0 123.0 121.0 149.0 122.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.50 stddev:2.00 (1.2%) stddev-sans-first:2.11 16:06:40 INFO - PROCESS | 20147 | Values: 161.0 160.0 161.0 161.0 162.0 162.0 167.0 161.0 162.0 160.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.50 stddev:13.44 (17.6%) stddev-sans-first:14.07 16:06:40 INFO - PROCESS | 20147 | Values: 76.0 77.0 77.0 115.0 76.0 77.0 76.0 75.0 98.0 75.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:84.00 stddev:10.35 (12.3%) stddev-sans-first:10.71 16:06:40 INFO - PROCESS | 20147 | Values: 84.0 84.0 109.0 85.0 84.0 84.0 103.0 84.0 84.0 104.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:06:40 INFO - PROCESS | 20147 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:06:40 INFO - PROCESS | 20147 | Values: 149.0 149.0 149.0 149.0 148.0 149.0 149.0 148.0 148.0 149.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:06:40 INFO - PROCESS | 20147 | Values: 95.0 95.0 95.0 95.0 96.0 94.0 95.0 95.0 96.0 95.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:59.50 stddev:1.91 (3.2%) stddev-sans-first:1.33 16:06:40 INFO - PROCESS | 20147 | Values: 64.0 58.0 58.0 59.0 59.0 60.0 59.0 59.0 62.0 61.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 16:06:40 INFO - PROCESS | 20147 | Values: 67.0 67.0 67.0 67.0 67.0 66.0 67.0 66.0 66.0 67.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.50 stddev:2.26 (2.7%) stddev-sans-first:2.37 16:06:40 INFO - PROCESS | 20147 | Values: 83.0 85.0 83.0 90.0 83.0 84.0 84.0 83.0 82.0 83.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:133.00 stddev:13.65 (10.3%) stddev-sans-first:14.42 16:06:40 INFO - PROCESS | 20147 | Values: 133.0 134.0 132.0 134.0 131.0 129.0 132.0 133.0 175.0 131.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:2.74 (1.6%) stddev-sans-first:1.42 16:06:40 INFO - PROCESS | 20147 | Values: 179.0 171.0 174.0 170.0 172.0 171.0 170.0 173.0 172.0 170.0 16:06:40 INFO - PROCESS | 20147 | 16:06:40 INFO - PROCESS | 20147 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 16:06:40 INFO - PROCESS | 20147 | Values: 67.0 67.0 67.0 67.0 67.0 68.0 67.0 66.0 67.0 67.0 16:06:40 INFO - PROCESS | 20147 | -------- Summary: end -------- 16:06:40 INFO - PROCESS | 20147 | 16:06:41 INFO - TEST-INFO | 20147: exit 0 16:06:41 INFO - TEST-OK | kraken | took 63268ms 16:06:41 INFO - SUITE-END | took 1183s 16:06:41 INFO - Completed test suite (00:19:43) 16:06:41 INFO - javascript benchmark 16:06:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2536.4546622476164, 2554.272032470746, 2566.3327534840946, 2571.138668546037, 2601.765841074303, 4564.863054108382, 4635.443746750395, 4687.250039993602, 4707.576318131368, 4724.4094488188975, 4702.623790096793, 4712.670113092085, 4728.0543891221805, 4760.309755085511, 4801.615870730342, 4262.360645903108, 4299.978500107495, 4339.2406328892475, 4368.825246990116, 4443.666724995622, 4017.758934463927, 4339.39248505208, 4381.539938306464, 4415.757133357664, 4444.555544445546, 4273.017206042628, 4367.061081867414, 4384.386185933972, 4430.224710675635, 4441.866743997685, 4295.914081718348, 4305.246581848164, 4336.392904993303, 4337.089211265638, 4354.368616550582, 4324.913501729948, 4330.870073897757, 4337.219300525904, 4350.695451318409, 4384.298512238027, 4726.83456079039, 4731.787069581843, 4738.64460165489, 4740.502247264057, 4742.596879265267, 4947.158982972904, 4977.129002424561, 4978.452370239271, 5044.47033061527, 5073.873153171164, 5002.074616196015, 5010.72441015744, 5053.797848086072, 5064.82273120439, 5128.666636668605, 4747.525247475225, 4750.144973904661, 4750.168720473903, 4752.572268495817, 4799.9760001199775, 5159.716215608102, 5163.173892177234, 5165.302684137593, 5170.043541944677, 5202.219667049897, 4847.684900481458, 4871.756412179377, 4914.459409464956, 4932.7286999215385, 4950.8514744557, 4923.0, 4995.725235112031, 5030.346055012825, 5033.194688849767, 5083.923741143886, 4901.852944411673, 4917.213245880714, 4966.329545511309, 4977.0, 5039.697618142924, 4975.0, 4983.800647974041, 4983.8504844854715, 4985.302057711923, 5024.447310795809, 4259.914801703949, 4268.189044081677, 4280.871573852789, 4292.892677683083, 4337.392765012901, 3708.6291370863237, 3711.2391959648207, 3726.6273372662517, 3730.403135498307, 3748.381517049711, 4335.067960388532, 4338.934915976263, 4340.131973605292, 4367.847125350598, 4374.0, 4937.975310123498, 4971.527704868032, 4971.751412429364, 5021.774020169101, 5024.296598476216, 4339.804708788049, 4376.518582955872, 4378.562143785596, 4386.561343865588, 4401.2517871961845, 4597.655175861823, 4604.447466303998, 4613.700109492872, 4615.076984603093, 4659.347691323217, 4597.540245975376, 4735.526447355306, 4736.50266722003, 4738.905221895612, 4780.689255198401, 4924.8768780780765, 4991.3511243538105, 5067.771950262173, 5075.365579302587, 5105.0, 4720.386349774644, 4723.551262630044, 4727.078219747116, 4728.976355118202, 4731.195696731633, 4970.155073637563, 4976.452590215146, 4976.477469865757, 4986.551210391082, 5022.74886255693, 4899.510048995072, 4927.926081108858, 4992.326035985241, 4998.750062496933, 5025.924611130763, 4877.879131143424, 4910.410750709938, 4911.63162762787, 4912.361393018885, 4938.901221975469, 4797.064572408347, 4804.95195048045, 4840.467548569725, 4863.173260545786, 4949.752512374439, 4809.2305231162845, 4827.565519103297, 4829.179039563212, 4856.4415092263425, 4890.462049174659, 4916.7541622919425, 4920.8769780755765, 4937.9259311109645, 4946.62900282473, 4975.726335051607, 4764.642651801199, 4821.228603423436, 4824.372831531879, 4844.0554091951735, 4873.098476781806], "value": 4617.3947553599355, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.1918602909608, 543.9700816455094, 546.7785546853523, 572.5076434266533, 574.9708022639476, 30019.399612007775, 39989.4001589976, 42452.0, 42466.57533424665, 46183.76908115455, 42839.57160428395, 43184.56815431845, 43286.567134328645, 43318.133637327315, 43395.78302108493, 7532.209118042609, 7717.72987945422, 7877.0, 7882.054153501588, 7886.6451009704415, 7432.14530329011, 7709.344705700022, 7712.575808330567, 9979.401235925867, 10261.640842570512, 10180.847287290697, 10196.1843052556, 10228.386296822177, 10253.333533320347, 10261.846072308921, 10116.494175291205, 10150.9492452538, 10158.238132140083, 10174.135198508136, 10180.949095254551, 9925.0, 10003.799924001552, 10004.749881252954, 10006.249531285182, 10030.89969100307, 39910.60089399127, 40244.0, 40308.193836123406, 40325.1934961302, 40346.59653403458, 3674.8346324415465, 3737.8131093445218, 3762.965184574264, 3777.773333599965, 3811.7331786774935, 28868.111165789272, 28992.0, 29210.41579168426, 29625.40749185026, 29890.40219195626, 3521.1725244567506, 3535.5580552430947, 3560.2345495718546, 3643.526341575605, 3763.3037887990536, 230083.0, 231462.0, 231588.0, 231669.0, 231892.0, 54614.45385546094, 54834.72582637061, 54850.17724734132, 55019.0, 55040.72479637656, 58394.0, 58436.70781646064, 58488.0, 58493.41506584965, 58540.0, 14751.631209219855, 14995.400183992519, 15017.699646007019, 15018.474353397798, 15027.699446011236, 11269.436528173559, 11350.14873884462, 11351.0, 11352.161959510122, 11356.26184298018, 15232.0, 15552.533423997298, 15600.765988510182, 16084.195790210442, 16111.194440278174, 5679.06911650041, 6247.75008999644, 6358.265945643325, 6450.838729031811, 6568.0, 785.8231897823058, 789.1831953927657, 790.4703848421572, 792.8810678398171, 817.3093735793245, 27374.0, 28220.0, 28271.57592636112, 28698.0, 28722.569161462594, 985.8324084905584, 986.5412583148727, 1010.0656892374524, 1013.5591017907086, 1049.968500944973, 14191.361388737532, 15226.162561059027, 15382.781772674956, 15391.846081539265, 15399.0, 343297.0, 345907.0, 346662.0, 349264.0, 349991.0, 50874.49125508771, 51152.48847511477, 51198.0, 51216.74391628018, 51388.22917656238, 1211.7213041000618, 1251.3180316727405, 1258.5217617305548, 1262.4571434283162, 1268.9111762176558, 25621.743782561934, 26497.0, 27112.72887271102, 27228.72771272262, 27272.863635681693, 42810.571894280656, 43321.13357732891, 43327.78336108426, 43391.56608433875, 43500.34749478697, 224379.0, 226456.0, 227286.0, 228194.0, 228640.0, 36217.29175207206, 36245.81877090598, 36624.45063323999, 36927.261454771295, 37003.44494832526, 39437.80281098576, 39561.406578901915, 39830.0, 40310.39534407045, 40828.0, 34417.31165376728, 34451.82774086113, 34533.13667158341, 34938.12654683551, 35068.64931350654, 33923.0, 34057.829710851285, 34502.65497344994, 34547.30905381828, 34891.30217395689, 34035.48946765751, 34271.97184084482, 34332.31335373328, 34662.0, 34849.47725784065, 29076.27309317284, 31628.841855790575, 31687.841560792047, 32046.6795332053, 32055.358892822478, 23069.307920762403, 23413.648795267745, 23688.0, 23946.144895400652, 24087.397815054763], "value": 17866.670922780242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.3495707726094, 366.6736604422063, 378.9276347935343, 387.93211188042096, 410.08960108559006, 13783.586492405231, 17299.913500432496, 18185.181666824992, 18957.24171033157, 19177.137028833702, 18187.636247275048, 18489.151084891506, 18731.250749969986, 18793.342233021845, 18796.0, 5056.140456122468, 5300.045991721508, 5302.3637163540425, 5306.867328316804, 5316.096263635172, 3948.466956960817, 4147.937780933288, 5175.844724658266, 5282.0, 5285.550728188108, 4145.0, 6079.726412311435, 6105.511559075263, 6212.502999760009, 6220.284667263255, 5276.657017293864, 5462.071447853875, 6174.814755557318, 6177.3822617738315, 6188.659623720693, 5469.917951230735, 5485.9725701371235, 5488.1493368527945, 5497.39528651852, 5505.614606977513, 12767.74464510714, 18176.363827266116, 18917.33789317381, 22232.555348892933, 22380.88809555958, 2212.5021870079263, 2228.7771222877745, 2231.252530402317, 2235.944101397462, 2323.0939933425975, 9126.726198214064, 9174.403663761834, 9278.675246366412, 9734.075262850018, 9751.463669498175, 1765.841074303306, 1767.7701898753207, 1774.4233124234547, 1775.5916139288036, 1819.245013319471, 73973.12322521278, 80069.59965200137, 80818.0, 80865.59567202185, 81309.18690813075, 9895.554700038516, 9932.553035113437, 10113.44376059309, 10618.469076546142, 10897.346159230472, 14665.92667036658, 14689.045212061395, 14693.485727999469, 14705.117692938458, 14705.632359190891, 7291.598962057032, 7525.2098529654695, 7537.0, 7729.1884352142215, 7736.110347310131, 7112.253213412517, 7136.0723105995885, 7299.270072992658, 7303.342699157101, 7320.7071717131785, 4405.625521830681, 4473.575010374009, 4519.977400112978, 4594.632429405639, 4614.377059097046, 1780.2433965564583, 1890.4801179675424, 1899.4586542835225, 1903.8889746795708, 1904.5714714189473, 814.8573999550055, 838.7609531283555, 854.9487030778173, 859.1408591408591, 871.5206636349982, 17427.215775290144, 17683.646327073642, 18318.54203644893, 18674.439766807016, 18905.90547047256, 837.7528629054443, 840.2018082821344, 850.2815121222557, 850.7447765670274, 860.0453496618749, 9671.613135474503, 10357.171426285879, 10484.790304193873, 10488.73778155537, 10509.159267258601, 497931.04137917765, 499360.0, 499383.0, 506795.0, 512007.0, 83974.58012709898, 85995.0, 86198.0, 87078.56460717655, 87326.56336718403, 881.9118088191258, 898.1377877237794, 908.2506931781174, 922.5064590444081, 923.732117685877, 15281.235938202823, 15365.539033829004, 16071.517854464384, 16307.266173022004, 16314.836851631331, 38289.617103828605, 38296.61703383042, 38304.42543361796, 39144.80427597844, 39208.607913920496, 51123.48876511187, 52085.0, 52803.0, 53962.73018634882, 55278.17082743842, 5367.275417818701, 6357.650329231937, 6572.868542629216, 7907.2488113629115, 7923.20767923203, 11537.711557210801, 12601.18092323996, 12859.357032148167, 13052.282124483245, 13205.471781128646, 10260.17918566498, 10380.532876020447, 10554.683359499228, 10933.086709825908, 11134.610288639858, 11066.225364224749, 11101.988824369546, 11143.108551315712, 11398.0, 11753.588624398106, 10405.427701476188, 10551.525181366704, 10681.732956676145, 10864.45677716127, 11085.445727713743, 7991.72028978983, 8003.6398362075015, 8093.595320233847, 8189.385796065199, 8240.340772738287, 9447.574859131493, 11007.229493935347, 11049.116070714163, 11188.992990630883, 11199.496022679163], "value": 8568.186852673749, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.4577923051545, 1332.0941759603468, 1345.4369426243004, 1346.0981142634434, 1348.0496250143647, 6594.703238354279, 6818.352256535633, 6889.621070841108, 6912.205096413914, 6934.479914006451, 6148.569600127993, 6291.213598300213, 6733.39399454049, 6824.0446337512785, 6852.0, 2100.548382097849, 2138.8182004529635, 2141.389703934371, 2145.2384403536753, 2147.624165770984, 2065.834733221346, 2103.4215590712524, 2115.947101322464, 2122.8089471947524, 2123.2462475821094, 1846.0861873372698, 1898.3450709505198, 2068.2037415595028, 2112.207922029239, 2131.957360852782, 2083.656196727538, 2086.69742887282, 2091.4248581640018, 2099.590579836932, 2101.495641046153, 2046.3144846476448, 2058.6706127019756, 2059.824914882237, 2067.6071546406283, 2096.5178009058004, 6994.965025174893, 7014.123234595676, 7036.859262814766, 7063.1524217094275, 7074.823129421754, 4255.446791917062, 4377.649788016951, 4381.408509851161, 4382.912341753147, 4384.890377740582, 2739.794515411352, 2878.4674835155356, 2898.536244192441, 2900.0284904556997, 2948.056621880999, 1109.8945600167972, 1150.9424528773527, 1179.109772122051, 1184.0, 1246.214884622691, 9143.954280228556, 9150.679726209617, 9162.587683554257, 9178.954105229497, 9273.768155796091, 3704.24063067076, 3960.3663413853646, 3963.801809909493, 4046.9595304046584, 4052.696047796427, 7852.764417067498, 7857.764267071882, 7858.882116768253, 7945.1657575953695, 7980.800479988046, 4354.303311470213, 4392.736435813861, 4393.231184542692, 4422.911541769211, 4428.689991700615, 3628.727845411605, 3739.495168152265, 3909.706771992112, 4067.7762723049923, 4075.694322925733, 3356.47974563943, 3362.36115138123, 3511.648835116468, 3524.947125793115, 3530.382183117944, 1133.6145710458318, 1138.8576427946507, 1144.4449442020614, 1147.839302497651, 1157.7279339355246, 664.299164381582, 676.749602647014, 757.7044952468542, 820.2986446588201, 842.9578521073922, 4453.554644535585, 4457.7771111444945, 4563.178627847019, 4692.859214223578, 4812.374391329105, 865.312076898867, 869.8651708985118, 876.6230520875957, 884.9159329863653, 928.396542247531, 3197.2646291352653, 3318.9336213276083, 3382.797032178077, 3387.542681737983, 3418.2908545727137, 8131.4714543555665, 8285.751427457186, 8366.623501942428, 8382.329413646894, 8401.411901166859, 8300.916990830136, 8335.791605209797, 8353.122922093215, 8357.958210209032, 8370.539620320818, 6365.14070600463, 6394.360563943568, 6552.737890484423, 6639.468842492685, 6653.800385988428, 4723.488483685188, 4757.524247575215, 4764.571188593043, 4826.107170173529, 4893.168161412497, 7657.425693072931, 7661.233876612294, 7683.001209842685, 7702.037245344332, 7762.805929851573, 7310.561366318038, 7596.696132154652, 7598.772036838904, 7810.648520816463, 7819.921800782147, 5581.944180558305, 5721.0, 6175.042868355412, 6215.564910456224, 6291.370862913672, 6345.206849143857, 6489.545731798728, 6519.022146678036, 6666.366695163952, 6678.699458524476, 5788.363280039276, 5834.38738932423, 6060.090986352083, 6147.66187859672, 6222.191115155001, 5770.884582308414, 5799.652020878592, 5814.883702326015, 5836.562257830595, 5954.76180952757, 5073.137566613611, 5213.113770658922, 5249.842504724865, 5304.814331498379, 5331.173668081454, 4108.630223279919, 4146.647534959562, 4254.574542545721, 4268.445102136827, 4333.090051089306, 4217.325227963511, 4305.117450922616, 4314.158739045856, 4346.0, 4414.492333381574], "value": 3808.135288977316, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.7231123567998, 354.21718003212897, 354.2914171656687, 354.60284481380853, 358.25125487730656, 4836.806527738886, 4840.467548569654, 4845.394325709286, 4863.537963893434, 4891.730954797485, 4787.6169906407495, 4810.831620893269, 4811.15804734171, 4816.807327706888, 4823.20417131173, 3840.519935008124, 3917.941230881539, 3933.645971862532, 3938.468306778577, 3940.901477463072, 3915.686745060388, 3925.60743925608, 3927.0771368728324, 3927.3127202739547, 3940.2710498557717, 3882.1265215326607, 3885.417187421881, 3890.4164375343785, 3893.493845800042, 3898.200868821885, 3781.8865434037025, 3882.5729169791302, 3907.2185562887535, 3910.4134379842967, 3915.5497117831246, 3482.89551313461, 3499.737519686034, 3514.38498262803, 3535.1338921964025, 3540.6105328413855, 4696.812127514895, 4698.533270033226, 4745.644076694227, 4752.550472106006, 4769.928451073237, 4449.199144154051, 4459.197344477992, 4465.196264672358, 4471.955280447187, 4483.663725220589, 4867.415910090812, 5087.720175390352, 5120.257562653432, 5130.97434512825, 5162.483751624845, 3866.7486613370043, 3877.3990031545154, 3885.8834234972996, 3893.2213557288655, 3897.6881849451975, 5049.697018178885, 5325.109586096919, 5333.706646134461, 5360.705161216131, 5370.516653501204, 3794.9563869935437, 3798.4492248624347, 3890.6498415142773, 3918.8236529356245, 3937.9170728049444, 3939.4484772131923, 3951.7431366961055, 3956.9406458902563, 3959.8318496043735, 3995.700322475826, 4180.331147016463, 4368.716033457815, 4402.669799765031, 4429.534898835575, 4432.8696182473905, 4967.254911763264, 4982.402111746614, 4982.77577509013, 4985.8781774100535, 4997.625178111656, 1312.6390242683337, 1325.1651459580596, 1330.4279159961116, 1330.5542643214437, 1331.740310639416, 936.3241817989373, 952.4380615436787, 953.2231231546267, 978.5792109393029, 994.8259054665549, 4269.573042695705, 4285.742855428622, 4295.0, 4331.610155085994, 4331.6318112960125, 4980.862056904127, 4996.625253106032, 5010.824621138316, 5014.222795466709, 5040.571551418097, 4251.872245054186, 4298.774849167972, 4314.460692413449, 4330.242207613655, 4351.3472979053395, 2583.9354016149746, 2676.892924283007, 2687.865606719656, 2692.0039585353543, 2702.9594556081674, 4760.714357138583, 4760.880977975578, 4766.356541866873, 4769.189237829577, 4772.689775164603, 4891.461939186686, 5173.301604283449, 5181.290174242491, 5184.407796101949, 5193.169092945111, 4725.40932383452, 4733.242681170997, 4737.431508219036, 4738.668293219511, 4740.5733483986605, 5014.921791814777, 5032.194848824171, 5032.27032080339, 5063.835317876836, 5077.796888124433, 4817.927731083966, 4968.726720030433, 4982.75086245679, 4988.575971042357, 5000.549950504326, 4659.184642687584, 4766.3327134200545, 4858.58702010333, 4867.926981095357, 4875.756212189447, 3615.74689771724, 3703.666669999713, 3710.2023065041144, 3714.7213958953726, 3729.645683660048, 5215.843524694265, 5224.2947202127225, 5247.606429517725, 5248.921266181087, 5255.763490642854, 4939.308496810521, 5052.343195384577, 5100.38795344561, 5104.489551044866, 5110.795568177231, 5042.243663450512, 5046.268291097844, 5048.419431765394, 5054.4187418447345, 5064.974675126601, 4993.201087825931, 4999.800007999639, 5016.316270795107, 5051.12086110928, 5075.2387141929, 4977.502249774993, 5032.496750324938, 5110.540051395392, 5123.692578445305, 5149.176131818891, 4962.33008543842, 4965.4289756678445, 4976.805566664047, 4976.825811096594, 4989.775460104376], "value": 3865.033122979156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.9760409583616, 624.7598516943868, 707.1584814071255, 724.565260843494, 736.4771012581069, 12350.382480875955, 13480.325983700825, 13496.392662330194, 13544.39050242739, 13545.864541354596, 13467.93266033671, 13550.322483875816, 13600.863991360084, 13658.863411365883, 13677.931610341959, 655.7606473637112, 676.0569006236298, 774.4540099230029, 792.6591565626778, 810.1007881251805, 793.3851265269404, 815.4047545291941, 816.0330008939396, 817.1787353709514, 817.652497688483, 863.2662237098478, 866.0819531296845, 868.2663149638545, 869.1048220333067, 870.0081906626451, 686.1114856261154, 709.3367701199395, 800.3637108498757, 858.716623514242, 858.9441686290403, 615.7690865925279, 626.8620903401245, 627.244170774216, 627.5575719118011, 627.8618703885138, 13921.164730116225, 13954.93022534891, 13955.441782328797, 13978.091424057506, 13993.650158746012, 11716.53133874644, 12008.879911200864, 12496.75006499874, 12563.93718031404, 12658.493660253578, 12154.209976351598, 12428.316442595653, 12465.439055242536, 12778.872211277861, 12829.61511154667, 4059.9188016239805, 4161.0845613964875, 4176.0603864130635, 4185.707000509966, 4205.516365617932, 63093.05360419597, 65017.67491162561, 65215.02177467342, 65768.0, 66777.66611166962, 10046.346987445793, 10072.697819065294, 10152.796944061225, 10180.23648226386, 10399.53202105907, 17491.737623935653, 17517.124143793015, 17517.737233941247, 17607.647847042987, 17667.11664416774, 6261.7182226799905, 6271.560990730696, 6319.115323854664, 6495.805125846232, 6518.543701940914, 3434.587849458081, 3519.296140771805, 3556.928861422757, 3562.8040457774555, 3597.640235976434, 2776.9583456248174, 2849.33040735427, 2869.096234686109, 2875.9856200718864, 2882.3514709190263, 457.35512926773754, 465.1952122827527, 469.94360676718327, 473.52410827118524, 473.5548584330718, 500.21216583880533, 522.0811371985333, 524.4126578232327, 562.4436934134369, 577.3331801768963, 7836.182521206587, 8202.958985205036, 8336.24973752353, 8404.663813447516, 8605.440646357967, 519.870032491877, 521.5306224398072, 533.786485405833, 535.9303290572278, 551.6331639459795, 3836.405357169643, 3853.9807300963876, 3881.6700580450415, 3900.3813417432016, 4028.1339512004474, 62129.37870621326, 62282.37717622856, 63964.36035639584, 64445.0, 65923.34076659172, 57828.79540841345, 58739.70630146822, 59096.70451647714, 59708.0, 59928.0, 53643.73178134162, 53822.0, 53845.461545384045, 54394.72802635962, 54523.0, 7286.234945330876, 7483.669766953653, 7673.34776544, 7845.646945887334, 7921.801954951173, 36004.639953600126, 36729.26541469102, 36989.44515832211, 37519.624803752704, 37678.24643507169, 37274.813625931696, 44836.77581612071, 45904.0, 45945.54054459546, 47154.05691886075, 8708.69519566812, 8769.254613357682, 8861.468311901306, 8868.201861832466, 8892.0, 9343.579538920902, 9450.291228157777, 9542.85685714701, 9562.521873906138, 9682.177014953808, 9352.3453358267, 9372.67195648149, 9403.717888463358, 9471.857922131312, 9479.810403792024, 8875.816341791611, 8921.553922303729, 8936.597853096757, 9022.954885225532, 9037.277017838689, 9377.343585948918, 9416.246700264099, 9420.717378478657, 9438.480883551196, 9446.480443575669, 7231.63841807918, 7466.962665186639, 7479.0, 7499.325060744559, 7539.547627142389, 6989.266127056586, 7006.0, 7147.3210045045635, 7257.419406447577, 7353.338199562065], "value": 5962.892369103509, "unit": "score"}], "name": "dromaeo_css", "value": 6291.937171835233, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 116.0, 116.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 125.0, 166.0, 124.0, 129.0, 123.0, 121.0, 149.0, 122.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 162.0, 162.0, 167.0, 161.0, 162.0, 160.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 115.0, 76.0, 77.0, 76.0, 75.0, 98.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 109.0, 85.0, 84.0, 84.0, 103.0, 84.0, 84.0, 104.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 148.0, 149.0, 149.0, 148.0, 148.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 58.0, 58.0, 59.0, 59.0, 60.0, 59.0, 59.0, 62.0, 61.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 90.0, 83.0, 84.0, 84.0, 83.0, 82.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 132.0, 134.0, 131.0, 129.0, 132.0, 133.0, 175.0, 131.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 171.0, 174.0, 170.0, 172.0, 171.0, 170.0, 173.0, 172.0, 170.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 66.0, 67.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1529.4, "alertThreshold": 2.0}]} 16:06:41 INFO - Return code: 0 16:06:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:06:41 INFO - # TBPL SUCCESS # 16:06:41 INFO - Running post-action listener: _resource_record_post_action 16:06:41 INFO - [mozharness: 2017-01-07 00:06:41.519255Z] Finished run-tests step (success) 16:06:41 INFO - Running post-run listener: _resource_record_post_run 16:06:41 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 386043904; Read time: 16; Write time: 241000 16:06:41 INFO - TinderboxPrint: CPU usage
13.1% 16:06:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:06:41 INFO - TinderboxPrint: I/O write bytes / time
386,043,904 / 241,000 16:06:41 INFO - TinderboxPrint: CPU idle
8,323.2 (86.9%) 16:06:41 INFO - TinderboxPrint: CPU user
1,211.2 (12.6%) 16:06:41 INFO - TinderboxPrint: Swap in / out
0 / 0 16:06:41 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 104153088; Read time: 0; Write time: 69156 16:06:41 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 281890816; Read time: 0; Write time: 171844 16:06:42 INFO - Running post-run listener: _upload_blobber_files 16:06:42 INFO - Blob upload gear active. 16:06:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:06:42 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 16:06:42 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'] 16:06:42 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 16:06:42 INFO - (blobuploader) - INFO - Open directory for files ... 16:06:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:06:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:43 INFO - (blobuploader) - INFO - Done attempting. 16:06:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:06:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:06:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:44 INFO - (blobuploader) - INFO - Done attempting. 16:06:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:06:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:06:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:48 INFO - (blobuploader) - INFO - Done attempting. 16:06:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:06:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:06:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:49 INFO - (blobuploader) - INFO - Done attempting. 16:06:49 INFO - (blobuploader) - INFO - Iteration through files over. 16:06:49 INFO - Return code: 0 16:06:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:06:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:06:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11758c97bd2911d5dcd89df18a51a2368077a0559a4e2000d0946e233787ab291ffcc043a83b9b0dd65b273d9f7af453dbbcad29c925d7bdc4558b0b659bf531", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4064436c512ddfe73cd1f1128d30ccf197d27a291df968fc01c49c95be48aba49b38b7ba072448f4cdf7f9b6da159150f157f033d598f14d199d7600de7b422b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ebfd79e2bf664b7c88d66fefdb2cd38f2d0a6d511f9435498db1db19cff2bbb503736b275fc8f8210f4f5d20643b8273bfcf04f8554e102354436d2a56e7ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c51b4a7cf5ff6e2ea8d410f7b641748a1b3bb8e017595a6bea1533bd19f80a7c17402a8c0bfd6d156e92961fa999f31adba1b9a772d78a149f3c601e11b90188"} 16:06:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:06:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:06:49 INFO - Contents: 16:06:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11758c97bd2911d5dcd89df18a51a2368077a0559a4e2000d0946e233787ab291ffcc043a83b9b0dd65b273d9f7af453dbbcad29c925d7bdc4558b0b659bf531", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4064436c512ddfe73cd1f1128d30ccf197d27a291df968fc01c49c95be48aba49b38b7ba072448f4cdf7f9b6da159150f157f033d598f14d199d7600de7b422b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ebfd79e2bf664b7c88d66fefdb2cd38f2d0a6d511f9435498db1db19cff2bbb503736b275fc8f8210f4f5d20643b8273bfcf04f8554e102354436d2a56e7ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c51b4a7cf5ff6e2ea8d410f7b641748a1b3bb8e017595a6bea1533bd19f80a7c17402a8c0bfd6d156e92961fa999f31adba1b9a772d78a149f3c601e11b90188"} 16:06:49 INFO - Running post-run listener: copy_logs_to_upload_dir 16:06:49 INFO - Copying logs to upload dir... 16:06:49 INFO - mkdir: /builds/slave/test/build/upload/logs 16:06:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.670003 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-06 16:06:49.678289) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 16:06:49.681439) ========= 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=bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406 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/11758c97bd2911d5dcd89df18a51a2368077a0559a4e2000d0946e233787ab291ffcc043a83b9b0dd65b273d9f7af453dbbcad29c925d7bdc4558b0b659bf531", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4064436c512ddfe73cd1f1128d30ccf197d27a291df968fc01c49c95be48aba49b38b7ba072448f4cdf7f9b6da159150f157f033d598f14d199d7600de7b422b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ebfd79e2bf664b7c88d66fefdb2cd38f2d0a6d511f9435498db1db19cff2bbb503736b275fc8f8210f4f5d20643b8273bfcf04f8554e102354436d2a56e7ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c51b4a7cf5ff6e2ea8d410f7b641748a1b3bb8e017595a6bea1533bd19f80a7c17402a8c0bfd6d156e92961fa999f31adba1b9a772d78a149f3c601e11b90188"} build_url:https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011684 build_url: 'https://queue.taskcluster.net/v1/task/aVOp32d_TR6wBhPhsR1clA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11758c97bd2911d5dcd89df18a51a2368077a0559a4e2000d0946e233787ab291ffcc043a83b9b0dd65b273d9f7af453dbbcad29c925d7bdc4558b0b659bf531", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4064436c512ddfe73cd1f1128d30ccf197d27a291df968fc01c49c95be48aba49b38b7ba072448f4cdf7f9b6da159150f157f033d598f14d199d7600de7b422b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ebfd79e2bf664b7c88d66fefdb2cd38f2d0a6d511f9435498db1db19cff2bbb503736b275fc8f8210f4f5d20643b8273bfcf04f8554e102354436d2a56e7ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c51b4a7cf5ff6e2ea8d410f7b641748a1b3bb8e017595a6bea1533bd19f80a7c17402a8c0bfd6d156e92961fa999f31adba1b9a772d78a149f3c601e11b90188"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 16:06:49.712389) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:06:49.712773) ========= 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=bb9b54edd4a927dc735d112f000001d9-1483746361.523092-360605406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:06:49.794716) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 16:06:49.796066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 16:06:49.796370) ========= ========= Total master_lag: 0.19 =========