builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-050 starttime: 1483986953.9 results: success (0) revision: d701e19a8a03b6e4d5653b0e72b604965b022cf0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:53.896834) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:53.897223) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:53.897503) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:53.928983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:53.929229) ========= 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=6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-09 10:35:55-- 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% 212M=0s 2017-01-09 10:35:55 (212 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122175 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:54.067555) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:54.067863) ========= 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=6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021972 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:54.119597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 10:35:54.119911) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev d701e19a8a03b6e4d5653b0e72b604965b022cf0 --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 d701e19a8a03b6e4d5653b0e72b604965b022cf0 --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=6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-09 10:35:55,510 truncating revision to first 12 chars 2017-01-09 10:35:55,510 Setting DEBUG logging. 2017-01-09 10:35:55,510 attempt 1/10 2017-01-09 10:35:55,510 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/d701e19a8a03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 10:35:56,175 unpacking tar archive at: graphics-d701e19a8a03/testing/mozharness/ program finished with exit code 0 elapsedTime=1.017346 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 10:35:55.150183) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:55.150481) ========= script_repo_revision: d701e19a8a03b6e4d5653b0e72b604965b022cf0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:55.150832) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:55.151107) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 10:35:55.160848) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-01-09 10:35:55.161166) ========= /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=6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:35:56 INFO - MultiFileLogger online at 20170109 10:35:56 in /builds/slave/test 10:35:56 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 10:35:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:35:56 INFO - {'append_to_log': False, 10:35:56 INFO - 'base_work_dir': '/builds/slave/test', 10:35:56 INFO - 'blob_upload_branch': 'Graphics', 10:35:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:35:56 INFO - 'branch': 'Graphics', 10:35:56 INFO - 'buildbot_json_path': 'buildprops.json', 10:35:56 INFO - 'config_files': ('talos/linux_config.py',), 10:35:56 INFO - 'default_actions': ('clobber', 10:35:56 INFO - 'read-buildbot-config', 10:35:56 INFO - 'download-and-extract', 10:35:56 INFO - 'populate-webroot', 10:35:56 INFO - 'create-virtualenv', 10:35:56 INFO - 'install', 10:35:56 INFO - 'run-tests'), 10:35:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:35:56 INFO - 'download_minidump_stackwalk': True, 10:35:56 INFO - 'download_symbols': 'ondemand', 10:35:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:35:56 INFO - 'tooltool.py': '/tools/tooltool.py', 10:35:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:35:56 INFO - '/tools/misc-python/virtualenv.py')}, 10:35:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:35:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:35:56 INFO - 'installer_path': 'installer.exe', 10:35:56 INFO - 'log_level': 'info', 10:35:56 INFO - 'log_name': 'talos', 10:35:56 INFO - 'log_to_console': True, 10:35:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:35:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:35:56 INFO - 'opt_config_files': (), 10:35:56 INFO - 'pip_index': False, 10:35:56 INFO - 'sps_profile': False, 10:35:56 INFO - 'sps_profile_interval': 0, 10:35:56 INFO - 'suite': 'dromaeojs', 10:35:56 INFO - 'system_bits': '32', 10:35:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:35:56 INFO - 'title': 'talos-linux64-ix-050', 10:35:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:35:56 INFO - 'use_talos_json': True, 10:35:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:35:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:35:56 INFO - 'work_dir': 'build'} 10:35:56 INFO - [mozharness: 2017-01-09 18:35:56.645763Z] Running clobber step. 10:35:56 INFO - Running pre-action listener: _resource_record_pre_action 10:35:56 INFO - Running main action method: clobber 10:35:56 INFO - rmtree: /builds/slave/test/build 10:35:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:35:57 INFO - Running post-action listener: _resource_record_post_action 10:35:57 INFO - [mozharness: 2017-01-09 18:35:57.118661Z] Finished clobber step (success) 10:35:57 INFO - [mozharness: 2017-01-09 18:35:57.118765Z] Running read-buildbot-config step. 10:35:57 INFO - Running pre-action listener: _resource_record_pre_action 10:35:57 INFO - Running main action method: read_buildbot_config 10:35:57 INFO - Using buildbot properties: 10:35:57 INFO - { 10:35:57 INFO - "project": "", 10:35:57 INFO - "product": "firefox", 10:35:57 INFO - "who": "kgupta@mozilla.com", 10:35:57 INFO - "installer_path": "public/build/target.tar.bz2", 10:35:57 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 10:35:57 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 10:35:57 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 10:35:57 INFO - "stage_platform": "linux64", 10:35:57 INFO - "basedir": "/builds/slave/test", 10:35:57 INFO - "buildnumber": 48, 10:35:57 INFO - "platform": "ubuntu64_hw", 10:35:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:35:57 INFO - "slavebuilddir": "test", 10:35:57 INFO - "taskId": "PPJl3jNyRUix-8Z0HEkPag", 10:35:57 INFO - "branch": "graphics", 10:35:57 INFO - "script_repo_revision": "default", 10:35:57 INFO - "revision": "d701e19a8a03b6e4d5653b0e72b604965b022cf0", 10:35:57 INFO - "slavename": "talos-linux64-ix-050", 10:35:57 INFO - "repo_path": "projects/graphics" 10:35:57 INFO - } 10:35:57 INFO - Finding installer, test and symbols from parent task. 10:35:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PPJl3jNyRUix-8Z0HEkPag'}, attempt #1 10:35:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PPJl3jNyRUix-8Z0HEkPag'}, attempt #1 10:35:58 INFO - Task dependencies: fq3JH72uR5-a7iRVwfvp_Q 10:35:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q'}, attempt #1 10:35:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2 10:35:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/test_packages.json 10:35:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.crashreporter-symbols.zip 10:35:58 INFO - Running post-action listener: _resource_record_post_action 10:35:58 INFO - [mozharness: 2017-01-09 18:35:58.502834Z] Finished read-buildbot-config step (success) 10:35:58 INFO - [mozharness: 2017-01-09 18:35:58.503055Z] Running download-and-extract step. 10:35:58 INFO - Running pre-action listener: _resource_record_pre_action 10:35:58 INFO - Running main action method: download_and_extract 10:35:58 INFO - mkdir: /builds/slave/test/build/tests 10:35:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:58 INFO - trying https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.test_packages.json 10:35:58 INFO - Downloading https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:35:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:35:59 INFO - Downloaded 896 bytes. 10:35:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:35:59 INFO - Using the following test package requirements: 10:35:59 INFO - {u'common': [u'target.common.tests.zip'], 10:35:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:35:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:35:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:35:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:35:59 INFO - u'mozbase': [u'target.common.tests.zip'], 10:35:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:35:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:35:59 INFO - u'web-platform': [u'target.common.tests.zip', 10:35:59 INFO - u'target.web-platform.tests.zip'], 10:35:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:35:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:35:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.common.tests.zip 10:35:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:35:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.common.tests.zip into memory 10:36:00 INFO - Expected file size: 20251352 10:36:00 INFO - Obtained file size: 20251352 10:36:04 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:36:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.common.tests.zip 10:36:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:36:04 INFO - Fetch https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.common.tests.zip into memory 10:36:05 INFO - Expected file size: 20251352 10:36:05 INFO - Obtained file size: 20251352 10:36:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.talos.tests.zip 10:36:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:36:09 INFO - Fetch https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.talos.tests.zip into memory 10:36:11 INFO - Expected file size: 14403537 10:36:11 INFO - Obtained file size: 14403537 10:36:11 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')]} 10:36:11 INFO - trying https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2 10:36:11 INFO - Downloading https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:36:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:36:13 INFO - Downloaded 56001353 bytes. 10:36:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2 10:36:13 INFO - mkdir: /builds/slave/test/properties 10:36:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:36:13 INFO - Writing to file /builds/slave/test/properties/build_url 10:36:13 INFO - Contents: 10:36:13 INFO - build_url:https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2 10:36:13 INFO - Running post-action listener: _resource_record_post_action 10:36:13 INFO - Running post-action listener: set_extra_try_arguments 10:36:13 INFO - [mozharness: 2017-01-09 18:36:13.604640Z] Finished download-and-extract step (success) 10:36:13 INFO - [mozharness: 2017-01-09 18:36:13.604764Z] Running populate-webroot step. 10:36:13 INFO - Running pre-action listener: _resource_record_pre_action 10:36:13 INFO - Running main action method: populate_webroot 10:36:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:36:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:36:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:36:13 INFO - '--tppagecycles', 10:36:13 INFO - '7'], 10:36:13 INFO - 'tests': ['tsvgm']}}, 10:36:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:36:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:36:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:36:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:36:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:36:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:36:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['damp', 'tps']}, 10:36:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'tests': ['damp', 'tps']}, 10:36:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['dromaeo_dom']}, 10:36:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:36:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['basic_compositor_video']}, 10:36:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:36:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['a11yr', 10:36:13 INFO - 'ts_paint', 10:36:13 INFO - 'tpaint', 10:36:13 INFO - 'sessionrestore', 10:36:13 INFO - 'sessionrestore_no_auto_restore', 10:36:13 INFO - 'tabpaint']}, 10:36:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:36:13 INFO - 'ts_paint', 10:36:13 INFO - 'tpaint', 10:36:13 INFO - 'sessionrestore', 10:36:13 INFO - 'sessionrestore_no_auto_restore', 10:36:13 INFO - 'tabpaint']}, 10:36:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:36:13 INFO - 'ts_paint', 10:36:13 INFO - 'tpaint', 10:36:13 INFO - 'sessionrestore', 10:36:13 INFO - 'sessionrestore_no_auto_restore', 10:36:13 INFO - 'tabpaint']}, 10:36:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:36:13 INFO - 'ts_paint', 10:36:13 INFO - 'tpaint', 10:36:13 INFO - 'sessionrestore', 10:36:13 INFO - 'sessionrestore_no_auto_restore', 10:36:13 INFO - 'tabpaint']}, 10:36:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['a11yr', 10:36:13 INFO - 'ts_paint', 10:36:13 INFO - 'tpaint', 10:36:13 INFO - 'sessionrestore', 10:36:13 INFO - 'sessionrestore_no_auto_restore', 10:36:13 INFO - 'tabpaint']}, 10:36:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['a11yr', 10:36:13 INFO - 'ts_paint', 10:36:13 INFO - 'tpaint', 10:36:13 INFO - 'sessionrestore', 10:36:13 INFO - 'sessionrestore_no_auto_restore', 10:36:13 INFO - 'tabpaint']}, 10:36:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['tsvgx', 10:36:13 INFO - 'tsvgr_opacity', 10:36:13 INFO - 'tart', 10:36:13 INFO - 'tscrollx', 10:36:13 INFO - 'cart', 10:36:13 INFO - 'tsvg_static']}, 10:36:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:36:13 INFO - 'tsvgr_opacity', 10:36:13 INFO - 'tart', 10:36:13 INFO - 'tscrollx', 10:36:13 INFO - 'cart', 10:36:13 INFO - 'tsvg_static']}, 10:36:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'talos_options': ['--disable-e10s'], 10:36:13 INFO - 'tests': ['tp5o']}, 10:36:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:36:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'talos_options': ['--disable-e10s', 10:36:13 INFO - '--xperf_path', 10:36:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:36:13 INFO - 'tests': ['tp5n']}, 10:36:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:36:13 INFO - 'talos_options': ['--xperf_path', 10:36:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:36:13 INFO - 'tests': ['tp5n']}}, 10:36:13 INFO - 'talos.zip': {'path': '', 10:36:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:36:13 INFO - Running post-action listener: _resource_record_post_action 10:36:13 INFO - [mozharness: 2017-01-09 18:36:13.621830Z] Finished populate-webroot step (success) 10:36:13 INFO - [mozharness: 2017-01-09 18:36:13.621925Z] Running create-virtualenv step. 10:36:13 INFO - Running pre-action listener: _resource_record_pre_action 10:36:13 INFO - Running main action method: create_virtualenv 10:36:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:36:13 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 10:36:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:36:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:36:13 INFO - Using real prefix '/usr' 10:36:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:36:14 INFO - Installing distribute.............................................................................................................................................................................................done. 10:36:16 INFO - Installing pip.................done. 10:36:16 INFO - Return code: 0 10:36:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:36:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:36:16 INFO - Reading from file tmpfile_stdout 10:36:16 INFO - Output received: 10:36:16 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) 10:36:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:16 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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:16 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 10:36:16 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 10:36:16 INFO - Using env: {'DISPLAY': ':0', 10:36:16 INFO - 'HOME': '/home/cltbld', 10:36:16 INFO - 'LANG': 'en_US.UTF-8', 10:36:16 INFO - 'LANGUAGE': 'en_US:en', 10:36:16 INFO - 'LOGNAME': 'cltbld', 10:36:16 INFO - 'MAIL': '/var/mail/cltbld', 10:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:16 INFO - 'MOZ_NO_REMOTE': '1', 10:36:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:16 INFO - 'NO_EM_RESTART': '1', 10:36:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:16 INFO - 'PWD': '/builds/slave/test', 10:36:16 INFO - 'SHELL': '/bin/bash', 10:36:16 INFO - 'SHLVL': '1', 10:36:16 INFO - 'TERM': 'linux', 10:36:16 INFO - 'TMOUT': '86400', 10:36:16 INFO - 'USER': 'cltbld', 10:36:16 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:16 INFO - '_': '/tools/buildbot/bin/python'} 10:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:16 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 10:36:16 INFO - Cleaning up... 10:36:16 INFO - Return code: 0 10:36:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:16 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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:16 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 10:36:16 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 10:36:16 INFO - Using env: {'DISPLAY': ':0', 10:36:16 INFO - 'HOME': '/home/cltbld', 10:36:16 INFO - 'LANG': 'en_US.UTF-8', 10:36:16 INFO - 'LANGUAGE': 'en_US:en', 10:36:16 INFO - 'LOGNAME': 'cltbld', 10:36:16 INFO - 'MAIL': '/var/mail/cltbld', 10:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:16 INFO - 'MOZ_NO_REMOTE': '1', 10:36:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:16 INFO - 'NO_EM_RESTART': '1', 10:36:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:16 INFO - 'PWD': '/builds/slave/test', 10:36:16 INFO - 'SHELL': '/bin/bash', 10:36:16 INFO - 'SHLVL': '1', 10:36:16 INFO - 'TERM': 'linux', 10:36:16 INFO - 'TMOUT': '86400', 10:36:16 INFO - 'USER': 'cltbld', 10:36:16 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:16 INFO - '_': '/tools/buildbot/bin/python'} 10:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:16 INFO - Downloading/unpacking psutil>=3.1.1 10:36:16 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 10:36:16 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 10:36:16 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 10:36:16 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 10:36:16 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 10:36:16 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 10:36:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:36:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:36:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:36:18 INFO - Installing collected packages: psutil 10:36:18 INFO - Running setup.py install for psutil 10:36:19 INFO - building 'psutil._psutil_linux' extension 10:36:19 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 10:36:19 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 10:36:19 INFO - building 'psutil._psutil_posix' extension 10:36:19 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 10:36:19 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 10:36:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:36:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:36:19 INFO - Successfully installed psutil 10:36:19 INFO - Cleaning up... 10:36:19 INFO - Return code: 0 10:36:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:36:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:36:19 INFO - Using env: {'DISPLAY': ':0', 10:36:19 INFO - 'HOME': '/home/cltbld', 10:36:19 INFO - 'LANG': 'en_US.UTF-8', 10:36:19 INFO - 'LANGUAGE': 'en_US:en', 10:36:19 INFO - 'LOGNAME': 'cltbld', 10:36:19 INFO - 'MAIL': '/var/mail/cltbld', 10:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:19 INFO - 'MOZ_NO_REMOTE': '1', 10:36:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:19 INFO - 'NO_EM_RESTART': '1', 10:36:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:19 INFO - 'PWD': '/builds/slave/test', 10:36:19 INFO - 'SHELL': '/bin/bash', 10:36:19 INFO - 'SHLVL': '1', 10:36:19 INFO - 'TERM': 'linux', 10:36:19 INFO - 'TMOUT': '86400', 10:36:19 INFO - 'USER': 'cltbld', 10:36:19 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:19 INFO - '_': '/tools/buildbot/bin/python'} 10:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:36:19 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 10:36:19 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 10:36:19 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 10:36:19 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 10:36:19 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 10:36:19 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 10:36:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:36:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:36:21 INFO - Installing collected packages: mozsystemmonitor 10:36:21 INFO - Running setup.py install for mozsystemmonitor 10:36:21 INFO - Successfully installed mozsystemmonitor 10:36:21 INFO - Cleaning up... 10:36:21 INFO - Return code: 0 10:36:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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', '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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:36: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 jsonschema==2.5.1 10:36:21 INFO - Using env: {'DISPLAY': ':0', 10:36:21 INFO - 'HOME': '/home/cltbld', 10:36:21 INFO - 'LANG': 'en_US.UTF-8', 10:36:21 INFO - 'LANGUAGE': 'en_US:en', 10:36:21 INFO - 'LOGNAME': 'cltbld', 10:36:21 INFO - 'MAIL': '/var/mail/cltbld', 10:36:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:21 INFO - 'MOZ_NO_REMOTE': '1', 10:36:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:21 INFO - 'NO_EM_RESTART': '1', 10:36:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:21 INFO - 'PWD': '/builds/slave/test', 10:36:21 INFO - 'SHELL': '/bin/bash', 10:36:21 INFO - 'SHLVL': '1', 10:36:21 INFO - 'TERM': 'linux', 10:36:21 INFO - 'TMOUT': '86400', 10:36:21 INFO - 'USER': 'cltbld', 10:36:21 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:21 INFO - '_': '/tools/buildbot/bin/python'} 10:36:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:36:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:36: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 10:36: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 10:36: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 10:36: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 10:36: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 10:36: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 10:36:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:36:24 INFO - warning: no files found matching '*.txt' 10:36:24 INFO - no previously-included directories found matching 'build' 10:36:24 INFO - no previously-included directories found matching 'dist' 10:36:24 INFO - no previously-included directories found matching '.git*' 10:36:24 INFO - Installing collected packages: jsonschema, functools32 10:36:24 INFO - Running setup.py install for functools32 10:36:24 INFO - warning: no files found matching '*.txt' 10:36:24 INFO - no previously-included directories found matching 'build' 10:36:24 INFO - no previously-included directories found matching 'dist' 10:36:24 INFO - no previously-included directories found matching '.git*' 10:36:24 INFO - Successfully installed jsonschema functools32 10:36:24 INFO - Cleaning up... 10:36:24 INFO - Return code: 0 10:36:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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', '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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:36: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 functools32==3.2.3-2 10:36:24 INFO - Using env: {'DISPLAY': ':0', 10:36:24 INFO - 'HOME': '/home/cltbld', 10:36:24 INFO - 'LANG': 'en_US.UTF-8', 10:36:24 INFO - 'LANGUAGE': 'en_US:en', 10:36:24 INFO - 'LOGNAME': 'cltbld', 10:36:24 INFO - 'MAIL': '/var/mail/cltbld', 10:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:24 INFO - 'MOZ_NO_REMOTE': '1', 10:36:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:24 INFO - 'NO_EM_RESTART': '1', 10:36:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:24 INFO - 'PWD': '/builds/slave/test', 10:36:24 INFO - 'SHELL': '/bin/bash', 10:36:24 INFO - 'SHLVL': '1', 10:36:24 INFO - 'TERM': 'linux', 10:36:24 INFO - 'TMOUT': '86400', 10:36:24 INFO - 'USER': 'cltbld', 10:36:24 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:24 INFO - '_': '/tools/buildbot/bin/python'} 10:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:36:24 INFO - Cleaning up... 10:36:24 INFO - Return code: 0 10:36:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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', '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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:36: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 blobuploader==1.2.4 10:36:24 INFO - Using env: {'DISPLAY': ':0', 10:36:24 INFO - 'HOME': '/home/cltbld', 10:36:24 INFO - 'LANG': 'en_US.UTF-8', 10:36:24 INFO - 'LANGUAGE': 'en_US:en', 10:36:24 INFO - 'LOGNAME': 'cltbld', 10:36:24 INFO - 'MAIL': '/var/mail/cltbld', 10:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:24 INFO - 'MOZ_NO_REMOTE': '1', 10:36:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:24 INFO - 'NO_EM_RESTART': '1', 10:36:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:24 INFO - 'PWD': '/builds/slave/test', 10:36:24 INFO - 'SHELL': '/bin/bash', 10:36:24 INFO - 'SHLVL': '1', 10:36:24 INFO - 'TERM': 'linux', 10:36:24 INFO - 'TMOUT': '86400', 10:36:24 INFO - 'USER': 'cltbld', 10:36:24 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:24 INFO - '_': '/tools/buildbot/bin/python'} 10:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:36:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:36:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:36: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 10:36: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 10:36: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 10:36: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 10:36: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 10:36: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 10:36:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:36:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:36: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 10:36: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 10:36: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 10:36: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 10:36: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 10:36: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 10:36:27 INFO - Downloading docopt-0.6.1.tar.gz 10:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:36:28 INFO - Installing collected packages: blobuploader, requests, docopt 10:36:28 INFO - Running setup.py install for blobuploader 10:36:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:36:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:36:28 INFO - Running setup.py install for requests 10:36:28 INFO - Running setup.py install for docopt 10:36:28 INFO - Successfully installed blobuploader requests docopt 10:36:28 INFO - Cleaning up... 10:36:28 INFO - Return code: 0 10:36:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:36:28 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')]} 10:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:28 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')]} 10:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:28 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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:28 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 10:36:28 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 10:36:28 INFO - Using env: {'DISPLAY': ':0', 10:36:28 INFO - 'HOME': '/home/cltbld', 10:36:28 INFO - 'LANG': 'en_US.UTF-8', 10:36:28 INFO - 'LANGUAGE': 'en_US:en', 10:36:28 INFO - 'LOGNAME': 'cltbld', 10:36:28 INFO - 'MAIL': '/var/mail/cltbld', 10:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:28 INFO - 'MOZ_NO_REMOTE': '1', 10:36:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:28 INFO - 'NO_EM_RESTART': '1', 10:36:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:28 INFO - 'PWD': '/builds/slave/test', 10:36:28 INFO - 'SHELL': '/bin/bash', 10:36:28 INFO - 'SHLVL': '1', 10:36:28 INFO - 'TERM': 'linux', 10:36:28 INFO - 'TMOUT': '86400', 10:36:28 INFO - 'USER': 'cltbld', 10:36:28 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:28 INFO - '_': '/tools/buildbot/bin/python'} 10:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:36:29 INFO - Running setup.py (path:/tmp/pip-8h6dXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:36:29 INFO - Running setup.py (path:/tmp/pip-2gH511-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:36:29 INFO - Running setup.py (path:/tmp/pip-9jDcYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:36:29 INFO - Running setup.py (path:/tmp/pip-D0IhEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:36:29 INFO - Running setup.py (path:/tmp/pip-3fUuXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:36:29 INFO - Running setup.py (path:/tmp/pip-9DXA5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:36:29 INFO - Running setup.py (path:/tmp/pip-oypFkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:36:29 INFO - Running setup.py (path:/tmp/pip-svP3LS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:36:29 INFO - Running setup.py (path:/tmp/pip-0H2bpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:36:29 INFO - Running setup.py (path:/tmp/pip-iBKeiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:36:29 INFO - Running setup.py (path:/tmp/pip-tz4s1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:36:29 INFO - Running setup.py (path:/tmp/pip-64VYZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:36:29 INFO - Running setup.py (path:/tmp/pip-fJBMmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:36:29 INFO - Running setup.py (path:/tmp/pip-uNNLey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:36:30 INFO - Running setup.py (path:/tmp/pip-etJjNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:36:30 INFO - Running setup.py (path:/tmp/pip-vzB4Lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:36:30 INFO - Running setup.py (path:/tmp/pip-9J7ZMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:36:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:36:30 INFO - Running setup.py install for manifestparser 10:36:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:36:30 INFO - Running setup.py install for mozcrash 10:36:30 INFO - Running setup.py install for mozdebug 10:36:30 INFO - Running setup.py install for mozdevice 10:36:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:36:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:36:30 INFO - Running setup.py install for mozfile 10:36:30 INFO - Running setup.py install for mozhttpd 10:36:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:36:30 INFO - Running setup.py install for mozinfo 10:36:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:36:30 INFO - Running setup.py install for mozInstall 10:36:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Running setup.py install for mozleak 10:36:31 INFO - Running setup.py install for mozlog 10:36:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Running setup.py install for moznetwork 10:36:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Running setup.py install for mozprocess 10:36:31 INFO - Running setup.py install for mozprofile 10:36:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Running setup.py install for mozrunner 10:36:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:36:31 INFO - Running setup.py install for mozscreenshot 10:36:31 INFO - Running setup.py install for moztest 10:36:31 INFO - Running setup.py install for mozversion 10:36:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:36:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:36:32 INFO - Cleaning up... 10:36:32 INFO - Return code: 0 10:36:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:36:32 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')]} 10:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:32 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')]} 10:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:32 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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:32 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 10:36:32 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 10:36:32 INFO - Using env: {'DISPLAY': ':0', 10:36:32 INFO - 'HOME': '/home/cltbld', 10:36:32 INFO - 'LANG': 'en_US.UTF-8', 10:36:32 INFO - 'LANGUAGE': 'en_US:en', 10:36:32 INFO - 'LOGNAME': 'cltbld', 10:36:32 INFO - 'MAIL': '/var/mail/cltbld', 10:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:32 INFO - 'MOZ_NO_REMOTE': '1', 10:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:32 INFO - 'NO_EM_RESTART': '1', 10:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:32 INFO - 'PWD': '/builds/slave/test', 10:36:32 INFO - 'SHELL': '/bin/bash', 10:36:32 INFO - 'SHLVL': '1', 10:36:32 INFO - 'TERM': 'linux', 10:36:32 INFO - 'TMOUT': '86400', 10:36:32 INFO - 'USER': 'cltbld', 10:36:32 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:32 INFO - '_': '/tools/buildbot/bin/python'} 10:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:36:32 INFO - Running setup.py (path:/tmp/pip-Vht5sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:36:32 INFO - Running setup.py (path:/tmp/pip-aOtSI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:36:32 INFO - Running setup.py (path:/tmp/pip-RyFA8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:36:32 INFO - Running setup.py (path:/tmp/pip-ihdlUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:36:32 INFO - Running setup.py (path:/tmp/pip-r224RN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:36:32 INFO - Running setup.py (path:/tmp/pip-t7oCjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:36:32 INFO - Running setup.py (path:/tmp/pip-GQkUC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:36:32 INFO - Running setup.py (path:/tmp/pip-R_Wr8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:36:32 INFO - Running setup.py (path:/tmp/pip-YkiYeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:36:32 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)) 10:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:36:32 INFO - Running setup.py (path:/tmp/pip-LZHys6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:36:33 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)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:36:33 INFO - Running setup.py (path:/tmp/pip-bw9Dim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:36:33 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)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:36:33 INFO - Running setup.py (path:/tmp/pip-Tf25Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:36:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:36:33 INFO - Running setup.py (path:/tmp/pip-gwK1rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:36:33 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)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:36:33 INFO - Running setup.py (path:/tmp/pip-AmaWea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:36:33 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)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:36:33 INFO - Running setup.py (path:/tmp/pip-zuFBTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:36:33 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)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:36:33 INFO - Running setup.py (path:/tmp/pip-3XmeUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:36:33 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)) 10:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:36:33 INFO - Running setup.py (path:/tmp/pip-wSLLxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:36:33 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)) 10:36:33 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)) 10:36:33 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)) 10:36:33 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)) 10:36:33 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)) 10:36:33 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)) 10:36:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:36:33 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 10:36:33 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 10:36:33 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 10:36:33 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 10:36:33 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 10:36:33 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 10:36:35 INFO - Downloading blessings-1.6.tar.gz 10:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:36:35 INFO - Installing collected packages: blessings 10:36:35 INFO - Running setup.py install for blessings 10:36:35 INFO - Successfully installed blessings 10:36:35 INFO - Cleaning up... 10:36:35 INFO - Return code: 0 10:36:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:36:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:36:36 INFO - Reading from file tmpfile_stdout 10:36:36 INFO - Current package versions: 10:36:36 INFO - argparse == 1.2.1 10:36:36 INFO - blessings == 1.6 10:36:36 INFO - blobuploader == 1.2.4 10:36:36 INFO - docopt == 0.6.1 10:36:36 INFO - functools32 == 3.2.3-2 10:36:36 INFO - jsonschema == 2.5.1 10:36:36 INFO - manifestparser == 1.1 10:36:36 INFO - mozInstall == 1.12 10:36:36 INFO - mozcrash == 1.0 10:36:36 INFO - mozdebug == 0.1 10:36:36 INFO - mozdevice == 0.49 10:36:36 INFO - mozfile == 1.2 10:36:36 INFO - mozhttpd == 0.7 10:36:36 INFO - mozinfo == 0.9 10:36:36 INFO - mozleak == 0.1 10:36:36 INFO - mozlog == 3.4 10:36:36 INFO - moznetwork == 0.27 10:36:36 INFO - mozprocess == 0.24 10:36:36 INFO - mozprofile == 0.28 10:36:36 INFO - mozrunner == 6.13 10:36:36 INFO - mozscreenshot == 0.1 10:36:36 INFO - mozsystemmonitor == 0.3 10:36:36 INFO - moztest == 0.8 10:36:36 INFO - mozversion == 1.4 10:36:36 INFO - psutil == 3.1.1 10:36:36 INFO - requests == 1.2.3 10:36:36 INFO - wsgiref == 0.1.2 10:36:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:36 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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:36 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 10:36:36 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 10:36:36 INFO - Using env: {'DISPLAY': ':0', 10:36:36 INFO - 'HOME': '/home/cltbld', 10:36:36 INFO - 'LANG': 'en_US.UTF-8', 10:36:36 INFO - 'LANGUAGE': 'en_US:en', 10:36:36 INFO - 'LOGNAME': 'cltbld', 10:36:36 INFO - 'MAIL': '/var/mail/cltbld', 10:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:36 INFO - 'MOZ_NO_REMOTE': '1', 10:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:36 INFO - 'NO_EM_RESTART': '1', 10:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:36 INFO - 'PWD': '/builds/slave/test', 10:36:36 INFO - 'SHELL': '/bin/bash', 10:36:36 INFO - 'SHLVL': '1', 10:36:36 INFO - 'TERM': 'linux', 10:36:36 INFO - 'TMOUT': '86400', 10:36:36 INFO - 'USER': 'cltbld', 10:36:36 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:36 INFO - '_': '/tools/buildbot/bin/python'} 10:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 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)) 10:36:36 INFO - Cleaning up... 10:36:36 INFO - Return code: 0 10:36:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:36 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 0x26e51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a56c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, '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': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', '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 10:36:36 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 10:36:36 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 10:36:36 INFO - Using env: {'DISPLAY': ':0', 10:36:36 INFO - 'HOME': '/home/cltbld', 10:36:36 INFO - 'LANG': 'en_US.UTF-8', 10:36:36 INFO - 'LANGUAGE': 'en_US:en', 10:36:36 INFO - 'LOGNAME': 'cltbld', 10:36:36 INFO - 'MAIL': '/var/mail/cltbld', 10:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:36 INFO - 'MOZ_NO_REMOTE': '1', 10:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:36 INFO - 'NO_EM_RESTART': '1', 10:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:36 INFO - 'PWD': '/builds/slave/test', 10:36:36 INFO - 'SHELL': '/bin/bash', 10:36:36 INFO - 'SHLVL': '1', 10:36:36 INFO - 'TERM': 'linux', 10:36:36 INFO - 'TMOUT': '86400', 10:36:36 INFO - 'USER': 'cltbld', 10:36:36 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:36 INFO - '_': '/tools/buildbot/bin/python'} 10:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:36:36 INFO - Cleaning up... 10:36:36 INFO - Return code: 0 10:36:36 INFO - Running post-action listener: _resource_record_post_action 10:36:36 INFO - Running post-action listener: _start_resource_monitoring 10:36:36 INFO - Starting resource monitoring. 10:36:36 INFO - [mozharness: 2017-01-09 18:36:36.610265Z] Finished create-virtualenv step (success) 10:36:36 INFO - [mozharness: 2017-01-09 18:36:36.610776Z] Running install step. 10:36:36 INFO - Running pre-action listener: _resource_record_pre_action 10:36:36 INFO - Running main action method: install 10:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:36:36 INFO - Reading from file tmpfile_stdout 10:36:36 INFO - Detecting whether we're running mozinstall >=1.0... 10:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:36:36 INFO - Reading from file tmpfile_stdout 10:36:36 INFO - Output received: 10:36:36 INFO - Usage: mozinstall [options] installer 10:36:36 INFO - Options: 10:36:36 INFO - -h, --help show this help message and exit 10:36:36 INFO - -d DEST, --destination=DEST 10:36:36 INFO - Directory to install application into. [default: 10:36:36 INFO - "/builds/slave/test"] 10:36:36 INFO - --app=APP Application being installed. [default: firefox] 10:36:36 INFO - mkdir: /builds/slave/test/build/application 10:36:36 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'] 10:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:36:53 INFO - Reading from file tmpfile_stdout 10:36:53 INFO - Output received: 10:36:53 INFO - /builds/slave/test/build/application/firefox/firefox 10:36:53 INFO - Running post-action listener: _resource_record_post_action 10:36:53 INFO - [mozharness: 2017-01-09 18:36:53.083411Z] Finished install step (success) 10:36:53 INFO - [mozharness: 2017-01-09 18:36:53.083701Z] Running run-tests step. 10:36:53 INFO - Running pre-action listener: _resource_record_pre_action 10:36:53 INFO - Running main action method: run_tests 10:36:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:36:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:36:53 INFO - Python 2.7.3 10:36:53 INFO - Return code: 0 10:36:53 INFO - grabbing minidump binary from tooltool 10:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:53 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 0x2a543f0>, '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 0x2a54cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a551a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:36:53 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 10:36:53 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 10:36:53 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 10:36:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:36:53 INFO - Return code: 0 10:36:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:36:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:36:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:36:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:36:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:36:53 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/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 10:36:53 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/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 10:36:53 INFO - Using env: {'DISPLAY': ':0', 10:36:53 INFO - 'HOME': '/home/cltbld', 10:36:53 INFO - 'LANG': 'en_US.UTF-8', 10:36:53 INFO - 'LANGUAGE': 'en_US:en', 10:36:53 INFO - 'LOGNAME': 'cltbld', 10:36:53 INFO - 'MAIL': '/var/mail/cltbld', 10:36:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:36:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:36:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:53 INFO - 'MOZ_NO_REMOTE': '1', 10:36:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:36:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:36:53 INFO - 'NO_EM_RESTART': '1', 10:36:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:36:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:53 INFO - 'PWD': '/builds/slave/test', 10:36:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:36:53 INFO - 'SHELL': '/bin/bash', 10:36:53 INFO - 'SHLVL': '1', 10:36:53 INFO - 'TERM': 'linux', 10:36:53 INFO - 'TMOUT': '86400', 10:36:53 INFO - 'USER': 'cltbld', 10:36:53 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370', 10:36:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:53 INFO - '_': '/tools/buildbot/bin/python'} 10:36:53 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/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 10:36:53 INFO - mozversion application_buildid: 20170109175847 10:36:53 INFO - mozversion application_changeset: d701e19a8a03b6e4d5653b0e72b604965b022cf0 10:36:53 INFO - mozversion application_display_name: Nightly 10:36:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:36:53 INFO - mozversion application_name: Firefox 10:36:53 INFO - mozversion application_remotingname: firefox 10:36:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 10:36:53 INFO - mozversion application_vendor: Mozilla 10:36:53 INFO - mozversion application_version: 53.0a1 10:36:53 INFO - mozversion platform_buildid: 20170109175847 10:36:53 INFO - mozversion platform_changeset: d701e19a8a03b6e4d5653b0e72b604965b022cf0 10:36:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 10:36:53 INFO - mozversion platform_version: 53.0a1 10:36:53 INFO - using testdate: 1483987013 10:36:53 INFO - actual date: 1483987013 10:36:53 INFO - starting webserver on 'localhost:47467' 10:36:53 INFO - SUITE-START | Running 2 tests 10:36:53 INFO - TEST-START | dromaeo_css 10:36:53 INFO - operating with platform_type : linux_ 10:36:53 INFO - Initialising browser for dromaeo_css test... 10:36:53 INFO - TEST-INFO | started process 26994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk6EYyl/profile http://localhost:47467/getInfo.html) 10:36:53 INFO - PROCESS | 26994 | 1483987013883 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < 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 10:36:57 INFO - PROCESS | 26994 | __metrics Screen width/height:1600/1200 10:36:57 INFO - PROCESS | 26994 | colorDepth:24 10:36:57 INFO - PROCESS | 26994 | Browser inner width/height: 1024/697 10:36:57 INFO - PROCESS | 26994 | __metrics 10:36:57 INFO - PROCESS | 26994 | 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" 10:36:57 INFO - PROCESS | 26994 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:36:58 INFO - PROCESS | 26994 | console.error: PushService: 10:36:58 INFO - PROCESS | 26994 | stateChangeProcessEnqueue: Error transitioning state 10:36:58 INFO - PROCESS | 26994 | UnknownError 10:36:58 INFO - TEST-INFO | 26994: exit 0 10:36:58 INFO - Browser initialized. 10:36:58 INFO - Running cycle 1/1 for dromaeo_css test... 10:36:58 INFO - TEST-INFO | started process 27077 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk6EYyl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:37:10 INFO - PROCESS | 27077 | RSS: Main: 152629248 10:37:10 INFO - PROCESS | 27077 | 10:40:16 INFO - PROCESS | 27077 | Cycle 1(1): loaded http://localhost:47467/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47467/tests/dromaeo/cssquery-ext.html) 10:40:17 INFO - PROCESS | 27077 | RSS: Main: 272658432 10:40:17 INFO - PROCESS | 27077 | 10:41:59 INFO - PROCESS | 27077 | ************************* 10:41:59 INFO - PROCESS | 27077 | A coding exception was thrown and uncaught in a Task. 10:41:59 INFO - PROCESS | 27077 | 10:41:59 INFO - PROCESS | 27077 | Full message: ReferenceError: fetch is not defined 10:41:59 INFO - PROCESS | 27077 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 10:41:59 INFO - PROCESS | 27077 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 10:41:59 INFO - PROCESS | 27077 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 10:41:59 INFO - PROCESS | 27077 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:41:59 INFO - PROCESS | 27077 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:41:59 INFO - PROCESS | 27077 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:41:59 INFO - PROCESS | 27077 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 10:41:59 INFO - PROCESS | 27077 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:41:59 INFO - PROCESS | 27077 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:41:59 INFO - PROCESS | 27077 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:41:59 INFO - PROCESS | 27077 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 10:41:59 INFO - PROCESS | 27077 | 10:41:59 INFO - PROCESS | 27077 | ************************* 10:43:20 INFO - PROCESS | 27077 | Cycle 1(1): loaded http://localhost:47467/tests/dromaeo/cssquery-ext.html (next: http://localhost:47467/tests/dromaeo/cssquery-jquery.html) 10:43:21 INFO - PROCESS | 27077 | RSS: Main: 241741824 10:43:21 INFO - PROCESS | 27077 | 10:46:23 INFO - PROCESS | 27077 | Cycle 1(1): loaded http://localhost:47467/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47467/tests/dromaeo/cssquery-mootools.html) 10:46:23 INFO - PROCESS | 27077 | RSS: Main: 226537472 10:46:23 INFO - PROCESS | 27077 | 10:49:26 INFO - PROCESS | 27077 | Cycle 1(1): loaded http://localhost:47467/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47467/tests/dromaeo/cssquery-prototype.html) 10:49:26 INFO - PROCESS | 27077 | RSS: Main: 218841088 10:49:26 INFO - PROCESS | 27077 | 10:52:30 INFO - PROCESS | 27077 | Cycle 1(1): loaded http://localhost:47467/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47467/tests/dromaeo/cssquery-yui.html) 10:52:30 INFO - PROCESS | 27077 | RSS: Main: 184823808 10:52:30 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | Cycle 1(1): loaded http://localhost:47467/tests/dromaeo/cssquery-yui.html (next: http://localhost:47467/tests/dromaeo/cssquery-dojo.html) 10:55:33 INFO - PROCESS | 27077 | RSS: Main: 279015424 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | __start_tp_report 10:55:33 INFO - PROCESS | 27077 | _x_x_mozilla_page_load 10:55:33 INFO - PROCESS | 27077 | _x_x_mozilla_page_load_details 10:55:33 INFO - PROCESS | 27077 | |i|pagename|runs| 10:55:33 INFO - PROCESS | 27077 | |0;dojo.html;2618.0967566189665;2649.244965184923;2669.559522678758;2670.9465810683787;2678.116221646858;4681.040386720722;4722.716637001778;4751.9762401187945;4796.424429068512;4841.854744357671;4759.524047595239;4790.592799612037;4817.518248175181;4819.566239038486;4896.363472748547;4351.956480435203;4365.759883206422;4407.008423104808;4443.044745379742;4526.0495295987885;4049.2508885856064;4392.0776636906285;4461.933071003938;4479.238529450001;4545.136424079431;4305.935410968838;4353.717008394476;4399.670024748157;4406.581374769392;4490.057088011522;4369.279068953619;4372.846950356754;4399.758013309267;4418.204723149832;4478.529754375776;4367.58507941745;4372.519022907478;4378.211921854065;4382.824687012548;4416.977915110436;4762.095201911626;4793.904121917542;4813.975930120362;4825.059113472874;4854.587360074398;5016.2224855147515;5072.492750724935;5083.38999320084;5142.845714628567;5148.304978827848;5171.120909445366;5182.766775495112;5184.766685499124;5194.117000109953;5225.320708307933;4852.417709874838;4853.02939412119;4858.271259311132;4859.271109333628;4909.828156014522;5115.64190506661;5160.019596276696;5160.225966105114;5312.707801070901;5359.410464848864;4994.8251811187165;5038.067957427887;5042.394912610437;5071.46749591295;5133.332666753298;5003.399592048904;5061.013102444987;5151.9484805152215;5159.2003239497935;5160.045391602565;5015.673981191211;5055.064813009605;5078.492150784892;5087.186050231945;5162.586993040548;5099.821506247264;5104.872378190501;5107.200445921986;5112.437631860493;5113.488651134857;4204.894877628084;4234.534201237861;4237.6821738369745;4270.914581708411;4281.935770963438;3770.5286839145106;3781.9810900945317;3787.223619158066;3800.201957588882;3800.4299355096955;4426;4427.313766366218;4428.844990425319;4429.446319210098;4433.135538569948;5138.07514647367;5139.152039913447;5142;5143.459936706667;5144.768485418165;4426.070525189746;4426.623736982328;4430.202563538594;4430.446194225722;4432.202203603382;4562.657800664963;4571.405717256735;4573.519780423074;4576.26779715244;4576.88557786058;4745.311929770164;4772.308015337826;4815.398075240525;4853.6602437829015;4855.174620314484;5124.231365295235;5149.073166830007;5149.794008239778;5177.068127737043;5182.196759502283;4747.074320507468;4781.258904869751;4791.688540244873;4854.417469903634;4888.364512613338;5065.594752419724;5097.7960881565805;5100.79596816138;5148.613853961043;5162.716050617252;5172.301739265151;5172.456892026283;5173.793048278027;5176.689398635944;5237.450067742984;4838.564529192389;4873.975630121969;5004.649674522749;5019.272205530251;5032.874178145576;4953.430355509018;4957.82647607332;4959.900801984012;4960.30555722192;4960.7767650456535;4843.757812109451;4871.707697538159;4893.21708526634;4946.03552307311;4956.182229932021;5020.096382651158;5025.221090730943;5027.824026159067;5029.497050295087;5071.087204303113;4897.608191344755;4898.804047838189;4900.117978763857;4900.632452566143;4942.604591632589 10:55:33 INFO - PROCESS | 27077 | |1;ext.html;518.8080410248208;525.7371314342827;534.2067030459767;537.4705914674046;544.5861145529396;29106.563401548992;39280.80359598202;40696.59303406965;40737.388939165896;45382;40737.59262407375;41081.58918410815;41125.38311925324;41227.58772412275;41248.58751412485;7568.167501574822;7693.807654808633;7870;7875.921240787576;7881;7553.811154721148;10022.198224142052;10329;10333.431661258628;10702.304350217211;10302.02130797573;10376.325538839988;10379.48102594871;10385.896141038605;10387.84418233727;10218.18254539635;10260.640877569287;10273.794524109477;10283;10288.228382871315;9671.854922176173;9742.269329800223;9749.463779492125;9759.804803903882;9762.267829912786;38555.61444385576;38959.8052009741;38971;38973.61026389728;39056.60943390558;3721.776693398405;3743.756655817363;3749.7750134991716;3771.0949372150762;3818.3127037133304;28228.717712822814;28378.574321385197;28381.290467738265;28639.713602863914;29589.704102959124;3307.1732066983254;3356.0938546592283;3393.490976353542;3416;3420.675035871588;224500;227733;229467;229948;231625;55121;55639.72180139073;55646.72176639091;55715.16427253594;55764.72117639386;58930.70534647299;58975.705121474115;59056.409435905094;59104;59178.40821591815;14246.430142794172;14653.633659158606;14665.413383464755;14679.706405871822;14688.19214943167;10104.09063184317;10400.375977441377;11294.265872718248;11301.095912326995;11322.660320190407;17375.13124343803;17425.651486970444;17428.912855435643;17485.562860928323;17522.649547008987;6293.213348331458;6351.841203969845;6454.933906394425;6499.837504062341;6553.508486863504;798.1699033005609;802.1537278171469;819.2381085590339;831.4595512916532;837.1586555511672;26215.427074375217;27893.72106278952;27898.581521277196;28431.431371372455;28552.428951420854;957.9904200958041;960.1071003966379;962.6438217859437;965.4448692001987;1047.2564479219839;15126;15140.091594504365;15269.236538173047;15307.15810630426;15398.692026159413;325846;327416;328030;328926;330194;49708.751456242484;49732.751336243084;49848.75075624599;49986;49995.50004499981;1195.6668314828903;1223.3944197622056;1228.7112528555783;1243.7512497500536;1249.1256120715536;25604.615930761425;26406.471870562098;26545.7345426551;27596.72403276022;27618;41360.1727965445;41718.79140604399;41789.58210417978;41887.79056104822;41907.58092419037;276393;276633;277375;278237;279161;32061.51907721433;33252.50121248232;33902;35950.82024589965;36451.45322820213;36260.456093159155;37014.25971480501;37032.814835925645;40867.795661021504;41482;31797.20506987344;31929.68070319267;31971.68028319687;32022;32545.674543254263;30969.535456968613;31998.52002219922;32597.674023259464;32788.67211327856;33293.83353083316;32197.839010804797;32371.51442728314;32518;32531.674683252866;32555.837220813744;25596.990107659214;25862.568860538744;28797.856010719814;29338.413231735674;29513;22786.316410507712;22957.426064347856;22996.310110696704;23196.420089497897;23256 10:55:33 INFO - PROCESS | 27077 | |2;jquery.html;369.2356821379743;375.0922766904093;377.3735598905816;397.2610943644822;398.6910144637905;16650.666986660275;18669.626607467842;18672.99999999998;18797.342093026746;18905.905470472662;17749.467515974542;18194.545136371566;18578.628427431442;18584.16371263293;18606.720899186523;5149.30484384607;5344.278522399484;5357.651752636086;5359.785608575652;5363.839084827442;5270.631055826094;5544.251526043973;5559.722013899309;5659.377468478485;5660.320761508625;5414.8917021659545;5626.184203290521;5638.04153293941;5644.266245388094;5650.63270887391;5539.833804985857;5576.944230557683;5602.747876345574;5603.691796951166;5625.578081643893;5293.285406470116;5458.263134476834;5459.344878614552;5483.890322193573;5509.889802203934;12333.506659733599;12517.874821251762;20255.885926274048;21292.893535532377;21606.243781467572;2129.371835308588;2157.395929139828;2239.3841693534246;2464.1621848571394;2492.4516606346574;9171.17459428648;9206.953965230197;9225.815483690289;9416.670416535388;9627.363348230818;1735.7309617009385;1740.4343588333716;1743.7122874725655;1767.761352217447;1768.2308195934681;76689.61655191744;76752.61623691901;78012;78027;78114.6094269525;9725;9805.705828825146;9949.452780097019;10379.429131397847;10513.369197848153;13850.307484625728;14153.433862645585;14204.289785510684;14211.43154273838;14259.500917467838;7129.287071292829;7293.854122917512;7313.853722925512;7480.962595186989;7483.6258187090425;6950.826229344307;6965;7111.288871112847;7145.392641625416;7154.499185056996;4348.499922508952;4422.513523512411;4449.57729015743;4509.165804326144;4520.276755719135;1806.4400035988622;1861.9293906003832;1881.8776779509287;1890.9905450472934;1897.2505860185427;777.4713195027435;807.3783186946018;808.3815880851154;813.3168138763349;828.5028982610386;17256.482305530855;17605.911970440324;17968.91015544914;18263.178156982704;18495.075246237902;784.8744200927824;803.9316658084011;811.1077814404108;822.0628483528782;843.7468759372164;5472.151816468454;5488.121900495901;5546.611737178439;5691.402402747652;5802.361740208574;480834;488856;518817;523194;523737;91907.5404622986;94223.52888235652;96878;97186;97714.02285977191;977.2768151567789;990.1138481059555;1020.2603112743202;1020.4387586827215;1025.9743506412399;9548.618055277711;9550.761230969281;9601;9855.408675479493;9982.151517120836;38415;39705.80147099246;39813.60186398099;41520;42166.57833421627;54731.72634136804;55056;56309.43690563042;56585.71707141438;57665.71167144137;7527.134379546423;7644.961775191088;7720.729774457867;7812.179721129427;7878.133405325295;9579.664711735057;11460.82808757886;11517.366544839779;11799.941000294944;12445.937770311091;8987.146221108984;9437.575309111244;9883.159931405911;9938.652147174815;10456.47717614101;10281.845772313272;10313.484325783531;10532.789344212919;10594.364338139736;10605.257631965997;9614.278929080207;9871.16095131893;10001.449920254165;10385.792284154124;10453.163746900382;7703.691852325845;7735.574543400167;7761.456698031084;7784.494007889469;7785.377169826287;8647.26498247656;9526.190273826802;9701.6604418845;9722.9027709722;9914.30599858003 10:55:33 INFO - PROCESS | 27077 | |3;mootools.html;1210.104522653237;1313.9544150462223;1320.2672516753198;1336.8529461759206;1354.9729005419888;6364.586301890374;6903.723851045958;7057.047298614685;7093.538919970199;7228.638568071588;7149.213586505493;7157.749478768244;7169.175544812349;7170.641467926595;7175.497715159928;1847.7136043913215;1906.1136571494253;2113.8837363944976;2130.7762684918093;2131.3073251193423;2054.3734161080865;2127.7446706395253;2139.775323591024;2140.7645159032495;2146.2059038155903;2041.4284000479815;2042.4383294594031;2051.620450216707;2053.7330147080856;2061.8453615978788;2069.8240649544805;2092.853500254983;2107.283523601975;2120.194326156051;2126.2133010786033;2048.109072553444;2058.4545095549693;2072.637288474511;2091.7071609974614;2095.5075557244036;7111.1822140454;7120.038794762692;7136.678849451787;7137.072180616538;7170.92829071713;4500.257457519506;4525.909481810379;4539.06949075442;4539.795709193094;4541.500434952152;2633.2626864477975;2692.3269182704325;2738.9589156162674;2778;2783.2902609834564;997.6358629100326;1027.45031408197;1032.0762917189074;1046.1369370269558;1070.0476575847501;9378.390404623678;9388.90611093894;9408.670696525589;9540.093691099404;9603.471809050568;3800.5059342285326;4068.7965601719798;4116.444280022219;4137.958620413818;4161.188568229166;8160.714374996846;8161.102278749333;8165.305948994402;8166.918330816735;8362.080171181165;4479.820807167742;4483.50681425043;4499.460064792181;4500.797464114123;4584.5186255442695;3693.372126738461;3749.8687545935763;3972.741771784825;4085.0604360997136;4131.97934010334;3333.1833700742986;3409.573803274591;3501.0547152268746;3504.632013638531;3509.1402606361603;1138.2828817844704;1142.6914733022038;1156.2311063143084;1157.9652610421701;1159.5303901919567;839.7102999465175;844.6748002019267;854.4702284583474;870.0951010948543;877.2630989968458;4613.653975951817;4750.928736068961;4810.5670489655395;4871.5128487151005;5027.773750181251;499.111581385135;501.6388200495637;506.8327451940851;516.2333934107846;518.3442944674989;3137.748980081588;3142.308692087737;3157.5105858591955;3204.2630195055267;3278.8360581970805;7986.081600616004;8168.7957801055445;8319.875201871977;8379.413441059067;8385.538795366314;8222.108914949595;8256.050554186346;8284.668613255522;8384.790380240544;8396.370272229608;6650.933490665128;6665.966670166618;6741.59550426976;6758.121444212221;6830.419414349836;4661.906761864811;4691.953080469151;4733.191141587115;4788.904221915612;4932.8520144395725;7552.962235188789;7572.167061623327;7622.847543049219;7647.770566883002;7689.154193038873;7577.469577129548;7604.771856844303;7653.3494652952895;7668.884966725615;7811.4922530035365;5691.686957217393;5881.088431293157;6168.599041062316;6196.1635179252025;6213.347598502092;6622.867542649024;6739.764108256188;6740.292269311848;6863.794086177422;6883.65581720922;6117.816465506042;6120.143179954899;6219.813405597839;6258.843528911813;6420.646864422315;6020.247469066367;6053.09203619443;6082.513398927987;6100.877982440237;6128.203333566607;5625.071863142535;5649.20911072442;5680.829575112753;5691.345495268097;5727.11229759388;4244.91510169801;4270.978645106755;4304.612584867377;4359.149965756774;4443.400140980926;4272.33778764292;4280.871573852789;4323.200207961537;4460.085682435158;4515.9548404516845 10:55:33 INFO - PROCESS | 27077 | |4;prototype.html;340.9624941256461;349.59447041431935;352.93117842020814;356.42437463496464;357.97136229101676;4881.365422495075;4909.729964851932;4922.1632322505175;4954.405471343435;4958.842903337047;4600.631949444045;4702.741349225791;4729.645276604251;4764.547368000034;4872.756362181885;3877.592852750463;3901.765894046352;3907.6483116519507;3908.7654740715507;3915.530136383638;3887.786171760552;3904.472896159025;3905.6094390561;3917.941230881539;3922.411638254256;3689.2252626948375;3816.2749077675153;3832.9808350958206;3888.416737489385;3927.253821773868;3853.171568112841;3891.8248678809236;3892.9416058759143;3893.922121557567;3911.6504805842083;3506.421440462321;3515.8417871195857;3516.7515531986032;3522.594901586325;3532.9646703532894;4599.862004139881;4760.690555113906;4793.544613261734;4794.880127996793;4816.807327706888;4449.488308844492;4475.530069342704;4477.2388693921785;4488.618467430272;4488.865334039984;5148.3821941366905;5159.277701121846;5173.534381905646;5247.212918062283;5258.237555554423;3863.961360386388;3869.690424766012;3871.128995975911;3913.6477717005323;3921.2353591049755;5177.326947496839;5376.354837419495;5376.596755243333;5385.43822291535;5388.73056347189;3934.940975885364;3936.980315098406;3939.266722642086;3963.4847469828737;3972.483577134954;4040.3333449980423;4042.312806822847;4046.7976601169826;4049;4063.79681015948;4298.3552467130185;4476.843310484118;4492.73043617384;4514.848713578082;4518.796654150506;5168.9483105168465;5170.5346518813485;5175.327207463081;5176.637635365563;5177.36912872444;1344.8117263583106;1358.8369395672582;1360.6190266725332;1362.7206422683325;1363.7545241856362;904.1320332480892;934.6915517879083;960.4093482508207;972.2610815780058;993.4089216915924;4330.761808100522;4339.978300108479;4371.803268852909;4374.606285434263;4420.491643461043;5153.974230128825;5162.664426812245;5175.663581867167;5181.740912954338;5191.974040129775;4309.332053531708;4397.780110994438;4417.403650507141;4428.82284708608;4449.57729015743;2746.945061098767;2752.7384898434616;2753.5731961545994;2765.875535600903;2772.209920172741;4731.787069581877;4820.204666230103;4831;4882.168342361422;4893.95106048942;5132.896427268155;5234.476552344811;5270.947290527046;5272.868178295574;5295.841124766263;4812.35033270504;4815.807367705393;4834.20235661119;4842.104210721028;4852.344933434012;5126.871828204325;5128.58971282304;5135.127028405254;5161.561267292323;5195.454477279831;4846.206903445747;5082.491750824888;5141.948580514147;5155.742212889416;5202.193659982709;4934.087193869145;4934.927330521262;4939.382577177853;4942;4944.516645006555;3684.465752465931;3692.1692619160476;3701.4262789267705;3701.5002974598083;3711.406175011985;5154.639175257846;5222.772648427541;5244.396894357045;5269.683818970874;5318.707471088973;5065.758889072201;5067.23991401278;5074;5074.3657042869645;5096.46487118862;5077.1876499759865;5102.744862756773;5111.667741596784;5124.898791495469;5138.845834624967;4909.239067944474;4992.400911890596;5020.347354843993;5021.84934451967;5048;4866.075445665312;4947.109520286383;5013.197888337849;5111.693298402108;5117.872053198551;4961.164369183425;4975.631701282176;4989.700617962933;4999.300097986212;5015.097282489187 10:55:33 INFO - PROCESS | 27077 | |5;yui.html;623.9688015599219;638.6678926957985;716.7742161219215;752.2590248605123;767.2289349204049;12886.742265154702;14205.431782728678;14207.928960355184;14216.075955062914;14252.358643861038;13563.52527661532;13704.725905481884;13728.862711372883;13825.308734563281;13862.237576933265;644.349207300625;662.2748090840546;775.8913752074714;794.900637420324;801.4069588504523;809.6356639512213;810.9067457242434;811.2982270336175;811.6996711216858;812.244612510368;855.8545047341967;856.1609622569854;856.297835774665;856.9357298202628;857.0700789643245;680.1090571351546;695.6243628440636;831.8793774902605;852.075498084579;852.9275011623988;621.8756248750223;628.9339619339995;629.3046183966696;629.4806784402886;629.7260691599168;12981.221126732426;12994.805077923838;13001.479940802357;13018.544350947672;13054.738905221842;12708.173968692097;12712.682182945407;12839.935800320938;12980.675483112904;13102.47590096395;10140.08739213467;10280.845787313196;10834.945825270903;11730.53077876884;11978.161528692995;4027.2549578328103;4040.5353384360587;4049.088954985134;4078.286299897506;4091.7724682595103;48405.03189936217;58682.413175868125;60621.39378606201;62025;62345.37654623441;10514.526846291936;10571.682849514527;10644.733881652866;10720.624778132884;11011.944940275247;17741.55646108832;17766.289348426177;17773.289068437116;17774.377896773552;17788.110594470225;6741.696623651947;6818.761343352959;6904.067950826574;6958.060661810692;7131.108611423572;3676.5771936227143;3815.065308999313;3873.8837834864435;3873.903152421212;3886.8639597613947;3075.938481230363;3133.686631336848;3155.542446345267;3158.7788854780406;3165.7150856422572;520.786477544205;535.7374886305682;538.7952578020405;562.1399259133532;587.0577722754916;621.5369549685513;632.5603705424686;641.6182371488957;644.7292137302344;659.8680263947134;8927.33045021626;8937.59780809852;9194.448333100036;9212.078792120734;9228.8615670765;626.1734510446258;635.6535688050024;640.2892789004202;641.7112299465259;648.6756621689156;4428.055328236944;5056.544910958031;5093.261477085802;5288.524032837063;5369.624126311199;63759.36240637627;63853.36146638567;64791;65056.67471662611;65224;53637.46362536325;54830;54857.72571137119;55565.44434555683;55679.443205568234;39949.60050399459;41834.37248441276;42260.78869605633;48267.275990860166;49006.01987960221;9614.71155865325;9973.45146016947;10087.243456740627;10093.848592271268;10100.696979090639;33024.66975330216;33614.663853361155;33702.15744606405;34437.65562344345;35003.64996350004;40172.598274018055;40368.39447408233;41914.742557723315;42396.36405453982;43199.784001079795;11177.05438666131;11338.433078346216;11347.943260283646;11528.538858445569;11538.653840384803;12750.553730619384;12844.678883027998;12903.41934612926;13145.34273286351;13695.520656777364;12192;12457.252564846138;12637.217923679766;12760.808587871375;12911.677208069874;12067.69830754203;12226.473067541787;12289.262644241375;12415.62753117408;12787.808182877077;11599.304041757521;11606.419679016184;11934.343611101473;11971.281723096643;12107.273563586214;10587.576496940035;10651.893481065401;10670.253082284165;10690.465476726287;11193.160512961724;9952;10012.599496020077;10055.69832905014;10058.647947321808;10093.343932644355 10:55:33 INFO - PROCESS | 27077 | __end_tp_report 10:55:33 INFO - PROCESS | 27077 | __start_cc_report 10:55:33 INFO - PROCESS | 27077 | _x_x_mozilla_cycle_collect,1287 10:55:33 INFO - PROCESS | 27077 | __end_cc_report 10:55:33 INFO - PROCESS | 27077 | __startTimestamp1483988133835__endTimestamp 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | ------- Summary: start ------- 10:55:33 INFO - PROCESS | 27077 | Number of tests: 6 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | [#0] dojo.html Cycles:165 Average:4733.16 Median:4858.27 stddev:505.25 (10.4%) stddev-sans-first:478.78 10:55:33 INFO - PROCESS | 27077 | Values: 2618.1 2649.2 2669.6 2670.9 2678.1 4681.0 4722.7 4752.0 4796.4 4841.9 4759.5 4790.6 4817.5 4819.6 4896.4 4352.0 4365.8 4407.0 4443.0 4526.0 4049.3 4392.1 4461.9 4479.2 4545.1 4305.9 4353.7 4399.7 4406.6 4490.1 4369.3 4372.8 4399.8 4418.2 4478.5 4367.6 4372.5 4378.2 4382.8 4417.0 4762.1 4793.9 4814.0 4825.1 4854.6 5016.2 5072.5 5083.4 5142.8 5148.3 5171.1 5182.8 5184.8 5194.1 5225.3 4852.4 4853.0 4858.3 4859.3 4909.8 5115.6 5160.0 5160.2 5312.7 5359.4 4994.8 5038.1 5042.4 5071.5 5133.3 5003.4 5061.0 5151.9 5159.2 5160.0 5015.7 5055.1 5078.5 5087.2 5162.6 5099.8 5104.9 5107.2 5112.4 5113.5 4204.9 4234.5 4237.7 4270.9 4281.9 3770.5 3782.0 3787.2 3800.2 3800.4 4426.0 4427.3 4428.8 4429.4 4433.1 5138.1 5139.2 5142.0 5143.5 5144.8 4426.1 4426.6 4430.2 4430.4 4432.2 4562.7 4571.4 4573.5 4576.3 4576.9 4745.3 4772.3 4815.4 4853.7 4855.2 5124.2 5149.1 5149.8 5177.1 5182.2 4747.1 4781.3 4791.7 4854.4 4888.4 5065.6 5097.8 5100.8 5148.6 5162.7 5172.3 5172.5 5173.8 5176.7 5237.5 4838.6 4874.0 5004.6 5019.3 5032.9 4953.4 4957.8 4959.9 4960.3 4960.8 4843.8 4871.7 4893.2 4946.0 4956.2 5020.1 5025.2 5027.8 5029.5 5071.1 4897.6 4898.8 4900.1 4900.6 4942.6 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | [#1] ext.html Cycles:180 Average:44069.34 Median:180006.84 stddev:73505.42 (40.8%) stddev-sans-first:73638.89 10:55:33 INFO - PROCESS | 27077 | Values: 518.8 525.7 534.2 537.5 544.6 29106.6 39280.8 40696.6 40737.4 45382.0 40737.6 41081.6 41125.4 41227.6 41248.6 7568.2 7693.8 7870.0 7875.9 7881.0 7553.8 10022.2 10329.0 10333.4 10702.3 10302.0 10376.3 10379.5 10385.9 10387.8 10218.2 10260.6 10273.8 10283.0 10288.2 9671.9 9742.3 9749.5 9759.8 9762.3 38555.6 38959.8 38971.0 38973.6 39056.6 3721.8 3743.8 3749.8 3771.1 3818.3 28228.7 28378.6 28381.3 28639.7 29589.7 3307.2 3356.1 3393.5 3416.0 3420.7 224500.0 227733.0 229467.0 229948.0 231625.0 55121.0 55639.7 55646.7 55715.2 55764.7 58930.7 58975.7 59056.4 59104.0 59178.4 14246.4 14653.6 14665.4 14679.7 14688.2 10104.1 10400.4 11294.3 11301.1 11322.7 17375.1 17425.7 17428.9 17485.6 17522.6 6293.2 6351.8 6454.9 6499.8 6553.5 798.2 802.2 819.2 831.5 837.2 26215.4 27893.7 27898.6 28431.4 28552.4 958.0 960.1 962.6 965.4 1047.3 15126.0 15140.1 15269.2 15307.2 15398.7 325846.0 327416.0 328030.0 328926.0 330194.0 49708.8 49732.8 49848.8 49986.0 49995.5 1195.7 1223.4 1228.7 1243.8 1249.1 25604.6 26406.5 26545.7 27596.7 27618.0 41360.2 41718.8 41789.6 41887.8 41907.6 276393.0 276633.0 277375.0 278237.0 279161.0 32061.5 33252.5 33902.0 35950.8 36451.5 36260.5 37014.3 37032.8 40867.8 41482.0 31797.2 31929.7 31971.7 32022.0 32545.7 30969.5 31998.5 32597.7 32788.7 33293.8 32197.8 32371.5 32518.0 32531.7 32555.8 25597.0 25862.6 28797.9 29338.4 29513.0 22786.3 22957.4 22996.3 23196.4 23256.0 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | [#2] jquery.html Cycles:180 Average:28319.38 Median:5486.01 stddev:83782.92 (1527.2%) stddev-sans-first:83991.66 10:55:33 INFO - PROCESS | 27077 | Values: 369.2 375.1 377.4 397.3 398.7 16650.7 18669.6 18673.0 18797.3 18905.9 17749.5 18194.5 18578.6 18584.2 18606.7 5149.3 5344.3 5357.7 5359.8 5363.8 5270.6 5544.3 5559.7 5659.4 5660.3 5414.9 5626.2 5638.0 5644.3 5650.6 5539.8 5576.9 5602.7 5603.7 5625.6 5293.3 5458.3 5459.3 5483.9 5509.9 12333.5 12517.9 20255.9 21292.9 21606.2 2129.4 2157.4 2239.4 2464.2 2492.5 9171.2 9207.0 9225.8 9416.7 9627.4 1735.7 1740.4 1743.7 1767.8 1768.2 76689.6 76752.6 78012.0 78027.0 78114.6 9725.0 9805.7 9949.5 10379.4 10513.4 13850.3 14153.4 14204.3 14211.4 14259.5 7129.3 7293.9 7313.9 7481.0 7483.6 6950.8 6965.0 7111.3 7145.4 7154.5 4348.5 4422.5 4449.6 4509.2 4520.3 1806.4 1861.9 1881.9 1891.0 1897.3 777.5 807.4 808.4 813.3 828.5 17256.5 17605.9 17968.9 18263.2 18495.1 784.9 803.9 811.1 822.1 843.7 5472.2 5488.1 5546.6 5691.4 5802.4 480834.0 488856.0 518817.0 523194.0 523737.0 91907.5 94223.5 96878.0 97186.0 97714.0 977.3 990.1 1020.3 1020.4 1026.0 9548.6 9550.8 9601.0 9855.4 9982.2 38415.0 39705.8 39813.6 41520.0 42166.6 54731.7 55056.0 56309.4 56585.7 57665.7 7527.1 7645.0 7720.7 7812.2 7878.1 9579.7 11460.8 11517.4 11799.9 12445.9 8987.1 9437.6 9883.2 9938.7 10456.5 10281.8 10313.5 10532.8 10594.4 10605.3 9614.3 9871.2 10001.4 10385.8 10453.2 7703.7 7735.6 7761.5 7784.5 7785.4 8647.3 9526.2 9701.7 9722.9 9914.3 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | [#3] mootools.html Cycles:180 Average:4670.28 Median:4841.04 stddev:2501.06 (51.7%) stddev-sans-first:2494.56 10:55:33 INFO - PROCESS | 27077 | Values: 1210.1 1314.0 1320.3 1336.9 1355.0 6364.6 6903.7 7057.0 7093.5 7228.6 7149.2 7157.7 7169.2 7170.6 7175.5 1847.7 1906.1 2113.9 2130.8 2131.3 2054.4 2127.7 2139.8 2140.8 2146.2 2041.4 2042.4 2051.6 2053.7 2061.8 2069.8 2092.9 2107.3 2120.2 2126.2 2048.1 2058.5 2072.6 2091.7 2095.5 7111.2 7120.0 7136.7 7137.1 7170.9 4500.3 4525.9 4539.1 4539.8 4541.5 2633.3 2692.3 2739.0 2778.0 2783.3 997.6 1027.5 1032.1 1046.1 1070.0 9378.4 9388.9 9408.7 9540.1 9603.5 3800.5 4068.8 4116.4 4138.0 4161.2 8160.7 8161.1 8165.3 8166.9 8362.1 4479.8 4483.5 4499.5 4500.8 4584.5 3693.4 3749.9 3972.7 4085.1 4132.0 3333.2 3409.6 3501.1 3504.6 3509.1 1138.3 1142.7 1156.2 1158.0 1159.5 839.7 844.7 854.5 870.1 877.3 4613.7 4750.9 4810.6 4871.5 5027.8 499.1 501.6 506.8 516.2 518.3 3137.7 3142.3 3157.5 3204.3 3278.8 7986.1 8168.8 8319.9 8379.4 8385.5 8222.1 8256.1 8284.7 8384.8 8396.4 6650.9 6666.0 6741.6 6758.1 6830.4 4661.9 4692.0 4733.2 4788.9 4932.9 7553.0 7572.2 7622.8 7647.8 7689.2 7577.5 7604.8 7653.3 7668.9 7811.5 5691.7 5881.1 6168.6 6196.2 6213.3 6622.9 6739.8 6740.3 6863.8 6883.7 6117.8 6120.1 6219.8 6258.8 6420.6 6020.2 6053.1 6082.5 6100.9 6128.2 5625.1 5649.2 5680.8 5691.3 5727.1 4244.9 4271.0 4304.6 4359.1 4443.4 4272.3 4280.9 4323.2 4460.1 4516.0 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | [#4] prototype.html Cycles:180 Average:4265.57 Median:4816.31 stddev:1193.29 (24.8%) stddev-sans-first:1159.71 10:55:33 INFO - PROCESS | 27077 | Values: 341.0 349.6 352.9 356.4 358.0 4881.4 4909.7 4922.2 4954.4 4958.8 4600.6 4702.7 4729.6 4764.5 4872.8 3877.6 3901.8 3907.6 3908.8 3915.5 3887.8 3904.5 3905.6 3917.9 3922.4 3689.2 3816.3 3833.0 3888.4 3927.3 3853.2 3891.8 3892.9 3893.9 3911.7 3506.4 3515.8 3516.8 3522.6 3533.0 4599.9 4760.7 4793.5 4794.9 4816.8 4449.5 4475.5 4477.2 4488.6 4488.9 5148.4 5159.3 5173.5 5247.2 5258.2 3864.0 3869.7 3871.1 3913.6 3921.2 5177.3 5376.4 5376.6 5385.4 5388.7 3934.9 3937.0 3939.3 3963.5 3972.5 4040.3 4042.3 4046.8 4049.0 4063.8 4298.4 4476.8 4492.7 4514.8 4518.8 5168.9 5170.5 5175.3 5176.6 5177.4 1344.8 1358.8 1360.6 1362.7 1363.8 904.1 934.7 960.4 972.3 993.4 4330.8 4340.0 4371.8 4374.6 4420.5 5154.0 5162.7 5175.7 5181.7 5192.0 4309.3 4397.8 4417.4 4428.8 4449.6 2746.9 2752.7 2753.6 2765.9 2772.2 4731.8 4820.2 4831.0 4882.2 4894.0 5132.9 5234.5 5270.9 5272.9 5295.8 4812.4 4815.8 4834.2 4842.1 4852.3 5126.9 5128.6 5135.1 5161.6 5195.5 4846.2 5082.5 5141.9 5155.7 5202.2 4934.1 4934.9 4939.4 4942.0 4944.5 3684.5 3692.2 3701.4 3701.5 3711.4 5154.6 5222.8 5244.4 5269.7 5318.7 5065.8 5067.2 5074.0 5074.4 5096.5 5077.2 5102.7 5111.7 5124.9 5138.8 4909.2 4992.4 5020.3 5021.8 5048.0 4866.1 4947.1 5013.2 5111.7 5117.9 4961.2 4975.6 4989.7 4999.3 5015.1 10:55:33 INFO - PROCESS | 27077 | 10:55:33 INFO - PROCESS | 27077 | [#5] yui.html Cycles:180 Average:14476.52 Median:22204.46 stddev:17105.59 (77.0%) stddev-sans-first:17121.94 10:55:33 INFO - PROCESS | 27077 | Values: 624.0 638.7 716.8 752.3 767.2 12886.7 14205.4 14207.9 14216.1 14252.4 13563.5 13704.7 13728.9 13825.3 13862.2 644.3 662.3 775.9 794.9 801.4 809.6 810.9 811.3 811.7 812.2 855.9 856.2 856.3 856.9 857.1 680.1 695.6 831.9 852.1 852.9 621.9 628.9 629.3 629.5 629.7 12981.2 12994.8 13001.5 13018.5 13054.7 12708.2 12712.7 12839.9 12980.7 13102.5 10140.1 10280.8 10834.9 11730.5 11978.2 4027.3 4040.5 4049.1 4078.3 4091.8 48405.0 58682.4 60621.4 62025.0 62345.4 10514.5 10571.7 10644.7 10720.6 11011.9 17741.6 17766.3 17773.3 17774.4 17788.1 6741.7 6818.8 6904.1 6958.1 7131.1 3676.6 3815.1 3873.9 3873.9 3886.9 3075.9 3133.7 3155.5 3158.8 3165.7 520.8 535.7 538.8 562.1 587.1 621.5 632.6 641.6 644.7 659.9 8927.3 8937.6 9194.4 9212.1 9228.9 626.2 635.7 640.3 641.7 648.7 4428.1 5056.5 5093.3 5288.5 5369.6 63759.4 63853.4 64791.0 65056.7 65224.0 53637.5 54830.0 54857.7 55565.4 55679.4 39949.6 41834.4 42260.8 48267.3 49006.0 9614.7 9973.5 10087.2 10093.8 10100.7 33024.7 33614.7 33702.2 34437.7 35003.6 40172.6 40368.4 41914.7 42396.4 43199.8 11177.1 11338.4 11347.9 11528.5 11538.7 12750.6 12844.7 12903.4 13145.3 13695.5 12192.0 12457.3 12637.2 12760.8 12911.7 12067.7 12226.5 12289.3 12415.6 12787.8 11599.3 11606.4 11934.3 11971.3 12107.3 10587.6 10651.9 10670.3 10690.5 11193.2 9952.0 10012.6 10055.7 10058.6 10093.3 10:55:33 INFO - PROCESS | 27077 | -------- Summary: end -------- 10:55:33 INFO - PROCESS | 27077 | 10:55:34 INFO - TEST-INFO | 27077: exit 0 10:55:34 INFO - TEST-OK | dromaeo_css | took 1121331ms 10:55:34 INFO - TEST-START | kraken 10:55:34 INFO - operating with platform_type : linux_ 10:55:34 INFO - Initialising browser for kraken test... 10:55:34 INFO - TEST-INFO | started process 27375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOY2Xfe/profile http://localhost:47467/getInfo.html) 10:55:35 INFO - PROCESS | 27375 | 1483988135178 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < 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 10:55:38 INFO - PROCESS | 27375 | __metrics Screen width/height:1600/1200 10:55:38 INFO - PROCESS | 27375 | colorDepth:24 10:55:38 INFO - PROCESS | 27375 | Browser inner width/height: 1024/697 10:55:38 INFO - PROCESS | 27375 | __metrics 10:55:39 INFO - PROCESS | 27375 | 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" 10:55:39 INFO - PROCESS | 27375 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:55:39 INFO - PROCESS | 27375 | console.error: PushService: 10:55:39 INFO - PROCESS | 27375 | stateChangeProcessEnqueue: Error transitioning state 10:55:39 INFO - PROCESS | 27375 | UnknownError 10:55:40 INFO - TEST-INFO | 27375: exit 0 10:55:40 INFO - Browser initialized. 10:55:40 INFO - Running cycle 1/1 for kraken test... 10:55:40 INFO - TEST-INFO | started process 27464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOY2Xfe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:55:52 INFO - PROCESS | 27464 | RSS: Main: 152027136 10:55:52 INFO - PROCESS | 27464 | 10:55:55 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47467/tests/kraken/driver.html?testName=audio-beat-detection) 10:55:55 INFO - PROCESS | 27464 | RSS: Main: 206753792 10:55:55 INFO - PROCESS | 27464 | 10:55:58 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47467/tests/kraken/driver.html?testName=audio-dft) 10:55:59 INFO - PROCESS | 27464 | RSS: Main: 185839616 10:55:59 INFO - PROCESS | 27464 | 10:56:02 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47467/tests/kraken/driver.html?testName=audio-fft) 10:56:02 INFO - PROCESS | 27464 | RSS: Main: 269045760 10:56:02 INFO - PROCESS | 27464 | 10:56:05 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47467/tests/kraken/driver.html?testName=audio-oscillator) 10:56:05 INFO - PROCESS | 27464 | RSS: Main: 180097024 10:56:05 INFO - PROCESS | 27464 | 10:56:07 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47467/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:56:08 INFO - PROCESS | 27464 | RSS: Main: 182747136 10:56:08 INFO - PROCESS | 27464 | 10:56:11 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47467/tests/kraken/driver.html?testName=imaging-darkroom) 10:56:12 INFO - PROCESS | 27464 | RSS: Main: 223002624 10:56:12 INFO - PROCESS | 27464 | 10:56:15 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47467/tests/kraken/driver.html?testName=imaging-desaturate) 10:56:16 INFO - PROCESS | 27464 | RSS: Main: 216387584 10:56:16 INFO - PROCESS | 27464 | 10:56:19 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47467/tests/kraken/driver.html?testName=json-parse-financial) 10:56:19 INFO - PROCESS | 27464 | RSS: Main: 218607616 10:56:19 INFO - PROCESS | 27464 | 10:56:21 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47467/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:56:22 INFO - PROCESS | 27464 | RSS: Main: 181866496 10:56:22 INFO - PROCESS | 27464 | 10:56:24 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:56:24 INFO - PROCESS | 27464 | RSS: Main: 189489152 10:56:24 INFO - PROCESS | 27464 | 10:56:26 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:56:27 INFO - PROCESS | 27464 | RSS: Main: 198041600 10:56:27 INFO - PROCESS | 27464 | 10:56:30 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:56:31 INFO - PROCESS | 27464 | RSS: Main: 201687040 10:56:31 INFO - PROCESS | 27464 | 10:56:34 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:56:34 INFO - PROCESS | 27464 | RSS: Main: 203247616 10:56:34 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | Cycle 1(1): loaded http://localhost:47467/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47467/tests/kraken/driver.html?testName=ai-astar) 10:56:36 INFO - PROCESS | 27464 | RSS: Main: 254808064 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | __start_tp_report 10:56:36 INFO - PROCESS | 27464 | _x_x_mozilla_page_load 10:56:36 INFO - PROCESS | 27464 | _x_x_mozilla_page_load_details 10:56:36 INFO - PROCESS | 27464 | |i|pagename|runs| 10:56:36 INFO - PROCESS | 27464 | |0;ai-astar;106;115;115;117;114;115;115;116;114;114 10:56:36 INFO - PROCESS | 27464 | |1;audio-beat-detection;147;134;130;162;124;128;123;126;144;124 10:56:36 INFO - PROCESS | 27464 | |2;audio-dft;160;163;161;162;160;161;161;162;162;161 10:56:36 INFO - PROCESS | 27464 | |3;audio-fft;75;78;78;109;77;77;77;77;104;76 10:56:36 INFO - PROCESS | 27464 | |4;audio-oscillator;86;84;120;85;84;85;106;86;86;123 10:56:36 INFO - PROCESS | 27464 | |5;imaging-gaussian-blur;118;117;117;117;117;117;118;117;118;118 10:56:36 INFO - PROCESS | 27464 | |6;imaging-darkroom;150;150;150;150;149;150;149;149;149;150 10:56:36 INFO - PROCESS | 27464 | |7;imaging-desaturate;95;96;96;95;95;96;95;95;96;95 10:56:36 INFO - PROCESS | 27464 | |8;json-parse-financial;60;58;59;60;59;61;59;60;60;59 10:56:36 INFO - PROCESS | 27464 | |9;json-stringify-tinderbox;69;69;71;68;68;68;68;68;68;69 10:56:36 INFO - PROCESS | 27464 | |10;stanford-crypto-aes;87;86;87;85;86;86;86;86;85;85 10:56:36 INFO - PROCESS | 27464 | |11;stanford-crypto-ccm;134;133;133;133;134;132;132;132;218;139 10:56:36 INFO - PROCESS | 27464 | |12;stanford-crypto-pbkdf2;180;180;181;180;184;182;179;186;178;178 10:56:36 INFO - PROCESS | 27464 | |13;stanford-crypto-sha256-iterative;69;70;69;69;68;68;68;68;68;72 10:56:36 INFO - PROCESS | 27464 | __end_tp_report 10:56:36 INFO - PROCESS | 27464 | __start_cc_report 10:56:36 INFO - PROCESS | 27464 | _x_x_mozilla_cycle_collect,1879 10:56:36 INFO - PROCESS | 27464 | __end_cc_report 10:56:36 INFO - PROCESS | 27464 | __startTimestamp1483988196837__endTimestamp 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | ------- Summary: start ------- 10:56:36 INFO - PROCESS | 27464 | Number of tests: 14 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.00 (2.6%) stddev-sans-first:1.00 10:56:36 INFO - PROCESS | 27464 | Values: 106.0 115.0 115.0 117.0 114.0 115.0 115.0 116.0 114.0 114.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:132.00 stddev:12.87 (9.7%) stddev-sans-first:12.78 10:56:36 INFO - PROCESS | 27464 | Values: 147.0 134.0 130.0 162.0 124.0 128.0 123.0 126.0 144.0 124.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 10:56:36 INFO - PROCESS | 27464 | Values: 160.0 163.0 161.0 162.0 160.0 161.0 161.0 162.0 162.0 161.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#3] audio-fft Cycles:10 Average:82.80 Median:77.00 stddev:12.58 (16.3%) stddev-sans-first:13.02 10:56:36 INFO - PROCESS | 27464 | Values: 75.0 78.0 78.0 109.0 77.0 77.0 77.0 77.0 104.0 76.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:85.00 stddev:15.68 (18.4%) stddev-sans-first:16.33 10:56:36 INFO - PROCESS | 27464 | Values: 86.0 84.0 120.0 85.0 84.0 85.0 106.0 86.0 86.0 123.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 10:56:36 INFO - PROCESS | 27464 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 118.0 118.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 10:56:36 INFO - PROCESS | 27464 | Values: 150.0 150.0 150.0 150.0 149.0 150.0 149.0 149.0 149.0 150.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:56:36 INFO - PROCESS | 27464 | Values: 95.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 10:56:36 INFO - PROCESS | 27464 | Values: 60.0 58.0 59.0 60.0 59.0 61.0 59.0 60.0 60.0 59.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.50 stddev:0.97 (1.4%) stddev-sans-first:1.01 10:56:36 INFO - PROCESS | 27464 | Values: 69.0 69.0 71.0 68.0 68.0 68.0 68.0 68.0 68.0 69.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:0.74 (0.9%) stddev-sans-first:0.67 10:56:36 INFO - PROCESS | 27464 | Values: 87.0 86.0 87.0 85.0 86.0 86.0 86.0 86.0 85.0 85.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:133.50 stddev:26.78 (20.1%) stddev-sans-first:28.25 10:56:36 INFO - PROCESS | 27464 | Values: 134.0 133.0 133.0 133.0 134.0 132.0 132.0 132.0 218.0 139.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:180.50 stddev:2.57 (1.4%) stddev-sans-first:2.71 10:56:36 INFO - PROCESS | 27464 | Values: 180.0 180.0 181.0 180.0 184.0 182.0 179.0 186.0 178.0 178.0 10:56:36 INFO - PROCESS | 27464 | 10:56:36 INFO - PROCESS | 27464 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.29 (1.9%) stddev-sans-first:1.36 10:56:36 INFO - PROCESS | 27464 | Values: 69.0 70.0 69.0 69.0 68.0 68.0 68.0 68.0 68.0 72.0 10:56:36 INFO - PROCESS | 27464 | -------- Summary: end -------- 10:56:36 INFO - PROCESS | 27464 | 10:56:37 INFO - TEST-INFO | 27464: exit 0 10:56:38 INFO - TEST-OK | kraken | took 63323ms 10:56:38 INFO - SUITE-END | took 1184s 10:56:38 INFO - Completed test suite (00:19:45) 10:56:38 INFO - javascript benchmark 10:56:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2618.0967566189665, 2649.244965184923, 2669.559522678758, 2670.9465810683787, 2678.116221646858, 4681.040386720722, 4722.716637001778, 4751.9762401187945, 4796.424429068512, 4841.854744357671, 4759.524047595239, 4790.592799612037, 4817.518248175181, 4819.566239038486, 4896.363472748547, 4351.956480435203, 4365.759883206422, 4407.008423104808, 4443.044745379742, 4526.0495295987885, 4049.2508885856064, 4392.0776636906285, 4461.933071003938, 4479.238529450001, 4545.136424079431, 4305.935410968838, 4353.717008394476, 4399.670024748157, 4406.581374769392, 4490.057088011522, 4369.279068953619, 4372.846950356754, 4399.758013309267, 4418.204723149832, 4478.529754375776, 4367.58507941745, 4372.519022907478, 4378.211921854065, 4382.824687012548, 4416.977915110436, 4762.095201911626, 4793.904121917542, 4813.975930120362, 4825.059113472874, 4854.587360074398, 5016.2224855147515, 5072.492750724935, 5083.38999320084, 5142.845714628567, 5148.304978827848, 5171.120909445366, 5182.766775495112, 5184.766685499124, 5194.117000109953, 5225.320708307933, 4852.417709874838, 4853.02939412119, 4858.271259311132, 4859.271109333628, 4909.828156014522, 5115.64190506661, 5160.019596276696, 5160.225966105114, 5312.707801070901, 5359.410464848864, 4994.8251811187165, 5038.067957427887, 5042.394912610437, 5071.46749591295, 5133.332666753298, 5003.399592048904, 5061.013102444987, 5151.9484805152215, 5159.2003239497935, 5160.045391602565, 5015.673981191211, 5055.064813009605, 5078.492150784892, 5087.186050231945, 5162.586993040548, 5099.821506247264, 5104.872378190501, 5107.200445921986, 5112.437631860493, 5113.488651134857, 4204.894877628084, 4234.534201237861, 4237.6821738369745, 4270.914581708411, 4281.935770963438, 3770.5286839145106, 3781.9810900945317, 3787.223619158066, 3800.201957588882, 3800.4299355096955, 4426.0, 4427.313766366218, 4428.844990425319, 4429.446319210098, 4433.135538569948, 5138.07514647367, 5139.152039913447, 5142.0, 5143.459936706667, 5144.768485418165, 4426.070525189746, 4426.623736982328, 4430.202563538594, 4430.446194225722, 4432.202203603382, 4562.657800664963, 4571.405717256735, 4573.519780423074, 4576.26779715244, 4576.88557786058, 4745.311929770164, 4772.308015337826, 4815.398075240525, 4853.6602437829015, 4855.174620314484, 5124.231365295235, 5149.073166830007, 5149.794008239778, 5177.068127737043, 5182.196759502283, 4747.074320507468, 4781.258904869751, 4791.688540244873, 4854.417469903634, 4888.364512613338, 5065.594752419724, 5097.7960881565805, 5100.79596816138, 5148.613853961043, 5162.716050617252, 5172.301739265151, 5172.456892026283, 5173.793048278027, 5176.689398635944, 5237.450067742984, 4838.564529192389, 4873.975630121969, 5004.649674522749, 5019.272205530251, 5032.874178145576, 4953.430355509018, 4957.82647607332, 4959.900801984012, 4960.30555722192, 4960.7767650456535, 4843.757812109451, 4871.707697538159, 4893.21708526634, 4946.03552307311, 4956.182229932021, 5020.096382651158, 5025.221090730943, 5027.824026159067, 5029.497050295087, 5071.087204303113, 4897.608191344755, 4898.804047838189, 4900.117978763857, 4900.632452566143, 4942.604591632589], "value": 4700.3667931685795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.8080410248208, 525.7371314342827, 534.2067030459767, 537.4705914674046, 544.5861145529396, 29106.563401548992, 39280.80359598202, 40696.59303406965, 40737.388939165896, 45382.0, 40737.59262407375, 41081.58918410815, 41125.38311925324, 41227.58772412275, 41248.58751412485, 7568.167501574822, 7693.807654808633, 7870.0, 7875.921240787576, 7881.0, 7553.811154721148, 10022.198224142052, 10329.0, 10333.431661258628, 10702.304350217211, 10302.02130797573, 10376.325538839988, 10379.48102594871, 10385.896141038605, 10387.84418233727, 10218.18254539635, 10260.640877569287, 10273.794524109477, 10283.0, 10288.228382871315, 9671.854922176173, 9742.269329800223, 9749.463779492125, 9759.804803903882, 9762.267829912786, 38555.61444385576, 38959.8052009741, 38971.0, 38973.61026389728, 39056.60943390558, 3721.776693398405, 3743.756655817363, 3749.7750134991716, 3771.0949372150762, 3818.3127037133304, 28228.717712822814, 28378.574321385197, 28381.290467738265, 28639.713602863914, 29589.704102959124, 3307.1732066983254, 3356.0938546592283, 3393.490976353542, 3416.0, 3420.675035871588, 224500.0, 227733.0, 229467.0, 229948.0, 231625.0, 55121.0, 55639.72180139073, 55646.72176639091, 55715.16427253594, 55764.72117639386, 58930.70534647299, 58975.705121474115, 59056.409435905094, 59104.0, 59178.40821591815, 14246.430142794172, 14653.633659158606, 14665.413383464755, 14679.706405871822, 14688.19214943167, 10104.09063184317, 10400.375977441377, 11294.265872718248, 11301.095912326995, 11322.660320190407, 17375.13124343803, 17425.651486970444, 17428.912855435643, 17485.562860928323, 17522.649547008987, 6293.213348331458, 6351.841203969845, 6454.933906394425, 6499.837504062341, 6553.508486863504, 798.1699033005609, 802.1537278171469, 819.2381085590339, 831.4595512916532, 837.1586555511672, 26215.427074375217, 27893.72106278952, 27898.581521277196, 28431.431371372455, 28552.428951420854, 957.9904200958041, 960.1071003966379, 962.6438217859437, 965.4448692001987, 1047.2564479219839, 15126.0, 15140.091594504365, 15269.236538173047, 15307.15810630426, 15398.692026159413, 325846.0, 327416.0, 328030.0, 328926.0, 330194.0, 49708.751456242484, 49732.751336243084, 49848.75075624599, 49986.0, 49995.50004499981, 1195.6668314828903, 1223.3944197622056, 1228.7112528555783, 1243.7512497500536, 1249.1256120715536, 25604.615930761425, 26406.471870562098, 26545.7345426551, 27596.72403276022, 27618.0, 41360.1727965445, 41718.79140604399, 41789.58210417978, 41887.79056104822, 41907.58092419037, 276393.0, 276633.0, 277375.0, 278237.0, 279161.0, 32061.51907721433, 33252.50121248232, 33902.0, 35950.82024589965, 36451.45322820213, 36260.456093159155, 37014.25971480501, 37032.814835925645, 40867.795661021504, 41482.0, 31797.20506987344, 31929.68070319267, 31971.68028319687, 32022.0, 32545.674543254263, 30969.535456968613, 31998.52002219922, 32597.674023259464, 32788.67211327856, 33293.83353083316, 32197.839010804797, 32371.51442728314, 32518.0, 32531.674683252866, 32555.837220813744, 25596.990107659214, 25862.568860538744, 28797.856010719814, 29338.413231735674, 29513.0, 22786.316410507712, 22957.426064347856, 22996.310110696704, 23196.420089497897, 23256.0], "value": 17654.478756562323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.2356821379743, 375.0922766904093, 377.3735598905816, 397.2610943644822, 398.6910144637905, 16650.666986660275, 18669.626607467842, 18672.99999999998, 18797.342093026746, 18905.905470472662, 17749.467515974542, 18194.545136371566, 18578.628427431442, 18584.16371263293, 18606.720899186523, 5149.30484384607, 5344.278522399484, 5357.651752636086, 5359.785608575652, 5363.839084827442, 5270.631055826094, 5544.251526043973, 5559.722013899309, 5659.377468478485, 5660.320761508625, 5414.8917021659545, 5626.184203290521, 5638.04153293941, 5644.266245388094, 5650.63270887391, 5539.833804985857, 5576.944230557683, 5602.747876345574, 5603.691796951166, 5625.578081643893, 5293.285406470116, 5458.263134476834, 5459.344878614552, 5483.890322193573, 5509.889802203934, 12333.506659733599, 12517.874821251762, 20255.885926274048, 21292.893535532377, 21606.243781467572, 2129.371835308588, 2157.395929139828, 2239.3841693534246, 2464.1621848571394, 2492.4516606346574, 9171.17459428648, 9206.953965230197, 9225.815483690289, 9416.670416535388, 9627.363348230818, 1735.7309617009385, 1740.4343588333716, 1743.7122874725655, 1767.761352217447, 1768.2308195934681, 76689.61655191744, 76752.61623691901, 78012.0, 78027.0, 78114.6094269525, 9725.0, 9805.705828825146, 9949.452780097019, 10379.429131397847, 10513.369197848153, 13850.307484625728, 14153.433862645585, 14204.289785510684, 14211.43154273838, 14259.500917467838, 7129.287071292829, 7293.854122917512, 7313.853722925512, 7480.962595186989, 7483.6258187090425, 6950.826229344307, 6965.0, 7111.288871112847, 7145.392641625416, 7154.499185056996, 4348.499922508952, 4422.513523512411, 4449.57729015743, 4509.165804326144, 4520.276755719135, 1806.4400035988622, 1861.9293906003832, 1881.8776779509287, 1890.9905450472934, 1897.2505860185427, 777.4713195027435, 807.3783186946018, 808.3815880851154, 813.3168138763349, 828.5028982610386, 17256.482305530855, 17605.911970440324, 17968.91015544914, 18263.178156982704, 18495.075246237902, 784.8744200927824, 803.9316658084011, 811.1077814404108, 822.0628483528782, 843.7468759372164, 5472.151816468454, 5488.121900495901, 5546.611737178439, 5691.402402747652, 5802.361740208574, 480834.0, 488856.0, 518817.0, 523194.0, 523737.0, 91907.5404622986, 94223.52888235652, 96878.0, 97186.0, 97714.02285977191, 977.2768151567789, 990.1138481059555, 1020.2603112743202, 1020.4387586827215, 1025.9743506412399, 9548.618055277711, 9550.761230969281, 9601.0, 9855.408675479493, 9982.151517120836, 38415.0, 39705.80147099246, 39813.60186398099, 41520.0, 42166.57833421627, 54731.72634136804, 55056.0, 56309.43690563042, 56585.71707141438, 57665.71167144137, 7527.134379546423, 7644.961775191088, 7720.729774457867, 7812.179721129427, 7878.133405325295, 9579.664711735057, 11460.82808757886, 11517.366544839779, 11799.941000294944, 12445.937770311091, 8987.146221108984, 9437.575309111244, 9883.159931405911, 9938.652147174815, 10456.47717614101, 10281.845772313272, 10313.484325783531, 10532.789344212919, 10594.364338139736, 10605.257631965997, 9614.278929080207, 9871.16095131893, 10001.449920254165, 10385.792284154124, 10453.163746900382, 7703.691852325845, 7735.574543400167, 7761.456698031084, 7784.494007889469, 7785.377169826287, 8647.26498247656, 9526.190273826802, 9701.6604418845, 9722.9027709722, 9914.30599858003], "value": 8243.562982252652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1210.104522653237, 1313.9544150462223, 1320.2672516753198, 1336.8529461759206, 1354.9729005419888, 6364.586301890374, 6903.723851045958, 7057.047298614685, 7093.538919970199, 7228.638568071588, 7149.213586505493, 7157.749478768244, 7169.175544812349, 7170.641467926595, 7175.497715159928, 1847.7136043913215, 1906.1136571494253, 2113.8837363944976, 2130.7762684918093, 2131.3073251193423, 2054.3734161080865, 2127.7446706395253, 2139.775323591024, 2140.7645159032495, 2146.2059038155903, 2041.4284000479815, 2042.4383294594031, 2051.620450216707, 2053.7330147080856, 2061.8453615978788, 2069.8240649544805, 2092.853500254983, 2107.283523601975, 2120.194326156051, 2126.2133010786033, 2048.109072553444, 2058.4545095549693, 2072.637288474511, 2091.7071609974614, 2095.5075557244036, 7111.1822140454, 7120.038794762692, 7136.678849451787, 7137.072180616538, 7170.92829071713, 4500.257457519506, 4525.909481810379, 4539.06949075442, 4539.795709193094, 4541.500434952152, 2633.2626864477975, 2692.3269182704325, 2738.9589156162674, 2778.0, 2783.2902609834564, 997.6358629100326, 1027.45031408197, 1032.0762917189074, 1046.1369370269558, 1070.0476575847501, 9378.390404623678, 9388.90611093894, 9408.670696525589, 9540.093691099404, 9603.471809050568, 3800.5059342285326, 4068.7965601719798, 4116.444280022219, 4137.958620413818, 4161.188568229166, 8160.714374996846, 8161.102278749333, 8165.305948994402, 8166.918330816735, 8362.080171181165, 4479.820807167742, 4483.50681425043, 4499.460064792181, 4500.797464114123, 4584.5186255442695, 3693.372126738461, 3749.8687545935763, 3972.741771784825, 4085.0604360997136, 4131.97934010334, 3333.1833700742986, 3409.573803274591, 3501.0547152268746, 3504.632013638531, 3509.1402606361603, 1138.2828817844704, 1142.6914733022038, 1156.2311063143084, 1157.9652610421701, 1159.5303901919567, 839.7102999465175, 844.6748002019267, 854.4702284583474, 870.0951010948543, 877.2630989968458, 4613.653975951817, 4750.928736068961, 4810.5670489655395, 4871.5128487151005, 5027.773750181251, 499.111581385135, 501.6388200495637, 506.8327451940851, 516.2333934107846, 518.3442944674989, 3137.748980081588, 3142.308692087737, 3157.5105858591955, 3204.2630195055267, 3278.8360581970805, 7986.081600616004, 8168.7957801055445, 8319.875201871977, 8379.413441059067, 8385.538795366314, 8222.108914949595, 8256.050554186346, 8284.668613255522, 8384.790380240544, 8396.370272229608, 6650.933490665128, 6665.966670166618, 6741.59550426976, 6758.121444212221, 6830.419414349836, 4661.906761864811, 4691.953080469151, 4733.191141587115, 4788.904221915612, 4932.8520144395725, 7552.962235188789, 7572.167061623327, 7622.847543049219, 7647.770566883002, 7689.154193038873, 7577.469577129548, 7604.771856844303, 7653.3494652952895, 7668.884966725615, 7811.4922530035365, 5691.686957217393, 5881.088431293157, 6168.599041062316, 6196.1635179252025, 6213.347598502092, 6622.867542649024, 6739.764108256188, 6740.292269311848, 6863.794086177422, 6883.65581720922, 6117.816465506042, 6120.143179954899, 6219.813405597839, 6258.843528911813, 6420.646864422315, 6020.247469066367, 6053.09203619443, 6082.513398927987, 6100.877982440237, 6128.203333566607, 5625.071863142535, 5649.20911072442, 5680.829575112753, 5691.345495268097, 5727.11229759388, 4244.91510169801, 4270.978645106755, 4304.612584867377, 4359.149965756774, 4443.400140980926, 4272.33778764292, 4280.871573852789, 4323.200207961537, 4460.085682435158, 4515.9548404516845], "value": 3795.9791764772567, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.9624941256461, 349.59447041431935, 352.93117842020814, 356.42437463496464, 357.97136229101676, 4881.365422495075, 4909.729964851932, 4922.1632322505175, 4954.405471343435, 4958.842903337047, 4600.631949444045, 4702.741349225791, 4729.645276604251, 4764.547368000034, 4872.756362181885, 3877.592852750463, 3901.765894046352, 3907.6483116519507, 3908.7654740715507, 3915.530136383638, 3887.786171760552, 3904.472896159025, 3905.6094390561, 3917.941230881539, 3922.411638254256, 3689.2252626948375, 3816.2749077675153, 3832.9808350958206, 3888.416737489385, 3927.253821773868, 3853.171568112841, 3891.8248678809236, 3892.9416058759143, 3893.922121557567, 3911.6504805842083, 3506.421440462321, 3515.8417871195857, 3516.7515531986032, 3522.594901586325, 3532.9646703532894, 4599.862004139881, 4760.690555113906, 4793.544613261734, 4794.880127996793, 4816.807327706888, 4449.488308844492, 4475.530069342704, 4477.2388693921785, 4488.618467430272, 4488.865334039984, 5148.3821941366905, 5159.277701121846, 5173.534381905646, 5247.212918062283, 5258.237555554423, 3863.961360386388, 3869.690424766012, 3871.128995975911, 3913.6477717005323, 3921.2353591049755, 5177.326947496839, 5376.354837419495, 5376.596755243333, 5385.43822291535, 5388.73056347189, 3934.940975885364, 3936.980315098406, 3939.266722642086, 3963.4847469828737, 3972.483577134954, 4040.3333449980423, 4042.312806822847, 4046.7976601169826, 4049.0, 4063.79681015948, 4298.3552467130185, 4476.843310484118, 4492.73043617384, 4514.848713578082, 4518.796654150506, 5168.9483105168465, 5170.5346518813485, 5175.327207463081, 5176.637635365563, 5177.36912872444, 1344.8117263583106, 1358.8369395672582, 1360.6190266725332, 1362.7206422683325, 1363.7545241856362, 904.1320332480892, 934.6915517879083, 960.4093482508207, 972.2610815780058, 993.4089216915924, 4330.761808100522, 4339.978300108479, 4371.803268852909, 4374.606285434263, 4420.491643461043, 5153.974230128825, 5162.664426812245, 5175.663581867167, 5181.740912954338, 5191.974040129775, 4309.332053531708, 4397.780110994438, 4417.403650507141, 4428.82284708608, 4449.57729015743, 2746.945061098767, 2752.7384898434616, 2753.5731961545994, 2765.875535600903, 2772.209920172741, 4731.787069581877, 4820.204666230103, 4831.0, 4882.168342361422, 4893.95106048942, 5132.896427268155, 5234.476552344811, 5270.947290527046, 5272.868178295574, 5295.841124766263, 4812.35033270504, 4815.807367705393, 4834.20235661119, 4842.104210721028, 4852.344933434012, 5126.871828204325, 5128.58971282304, 5135.127028405254, 5161.561267292323, 5195.454477279831, 4846.206903445747, 5082.491750824888, 5141.948580514147, 5155.742212889416, 5202.193659982709, 4934.087193869145, 4934.927330521262, 4939.382577177853, 4942.0, 4944.516645006555, 3684.465752465931, 3692.1692619160476, 3701.4262789267705, 3701.5002974598083, 3711.406175011985, 5154.639175257846, 5222.772648427541, 5244.396894357045, 5269.683818970874, 5318.707471088973, 5065.758889072201, 5067.23991401278, 5074.0, 5074.3657042869645, 5096.46487118862, 5077.1876499759865, 5102.744862756773, 5111.667741596784, 5124.898791495469, 5138.845834624967, 4909.239067944474, 4992.400911890596, 5020.347354843993, 5021.84934451967, 5048.0, 4866.075445665312, 4947.109520286383, 5013.197888337849, 5111.693298402108, 5117.872053198551, 4961.164369183425, 4975.631701282176, 4989.700617962933, 4999.300097986212, 5015.097282489187], "value": 3903.753152937556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.9688015599219, 638.6678926957985, 716.7742161219215, 752.2590248605123, 767.2289349204049, 12886.742265154702, 14205.431782728678, 14207.928960355184, 14216.075955062914, 14252.358643861038, 13563.52527661532, 13704.725905481884, 13728.862711372883, 13825.308734563281, 13862.237576933265, 644.349207300625, 662.2748090840546, 775.8913752074714, 794.900637420324, 801.4069588504523, 809.6356639512213, 810.9067457242434, 811.2982270336175, 811.6996711216858, 812.244612510368, 855.8545047341967, 856.1609622569854, 856.297835774665, 856.9357298202628, 857.0700789643245, 680.1090571351546, 695.6243628440636, 831.8793774902605, 852.075498084579, 852.9275011623988, 621.8756248750223, 628.9339619339995, 629.3046183966696, 629.4806784402886, 629.7260691599168, 12981.221126732426, 12994.805077923838, 13001.479940802357, 13018.544350947672, 13054.738905221842, 12708.173968692097, 12712.682182945407, 12839.935800320938, 12980.675483112904, 13102.47590096395, 10140.08739213467, 10280.845787313196, 10834.945825270903, 11730.53077876884, 11978.161528692995, 4027.2549578328103, 4040.5353384360587, 4049.088954985134, 4078.286299897506, 4091.7724682595103, 48405.03189936217, 58682.413175868125, 60621.39378606201, 62025.0, 62345.37654623441, 10514.526846291936, 10571.682849514527, 10644.733881652866, 10720.624778132884, 11011.944940275247, 17741.55646108832, 17766.289348426177, 17773.289068437116, 17774.377896773552, 17788.110594470225, 6741.696623651947, 6818.761343352959, 6904.067950826574, 6958.060661810692, 7131.108611423572, 3676.5771936227143, 3815.065308999313, 3873.8837834864435, 3873.903152421212, 3886.8639597613947, 3075.938481230363, 3133.686631336848, 3155.542446345267, 3158.7788854780406, 3165.7150856422572, 520.786477544205, 535.7374886305682, 538.7952578020405, 562.1399259133532, 587.0577722754916, 621.5369549685513, 632.5603705424686, 641.6182371488957, 644.7292137302344, 659.8680263947134, 8927.33045021626, 8937.59780809852, 9194.448333100036, 9212.078792120734, 9228.8615670765, 626.1734510446258, 635.6535688050024, 640.2892789004202, 641.7112299465259, 648.6756621689156, 4428.055328236944, 5056.544910958031, 5093.261477085802, 5288.524032837063, 5369.624126311199, 63759.36240637627, 63853.36146638567, 64791.0, 65056.67471662611, 65224.0, 53637.46362536325, 54830.0, 54857.72571137119, 55565.44434555683, 55679.443205568234, 39949.60050399459, 41834.37248441276, 42260.78869605633, 48267.275990860166, 49006.01987960221, 9614.71155865325, 9973.45146016947, 10087.243456740627, 10093.848592271268, 10100.696979090639, 33024.66975330216, 33614.663853361155, 33702.15744606405, 34437.65562344345, 35003.64996350004, 40172.598274018055, 40368.39447408233, 41914.742557723315, 42396.36405453982, 43199.784001079795, 11177.05438666131, 11338.433078346216, 11347.943260283646, 11528.538858445569, 11538.653840384803, 12750.553730619384, 12844.678883027998, 12903.41934612926, 13145.34273286351, 13695.520656777364, 12192.0, 12457.252564846138, 12637.217923679766, 12760.808587871375, 12911.677208069874, 12067.69830754203, 12226.473067541787, 12289.262644241375, 12415.62753117408, 12787.808182877077, 11599.304041757521, 11606.419679016184, 11934.343611101473, 11971.281723096643, 12107.273563586214, 10587.576496940035, 10651.893481065401, 10670.253082284165, 10690.465476726287, 11193.160512961724, 9952.0, 10012.599496020077, 10055.69832905014, 10058.647947321808, 10093.343932644355], "value": 6472.2387185481275, "unit": "score"}], "name": "dromaeo_css", "value": 6350.906785151156, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 115.0, 117.0, 114.0, 115.0, 115.0, 116.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 134.0, 130.0, 162.0, 124.0, 128.0, 123.0, 126.0, 144.0, 124.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 161.0, 162.0, 160.0, 161.0, 161.0, 162.0, 162.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 78.0, 109.0, 77.0, 77.0, 77.0, 77.0, 104.0, 76.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 120.0, 85.0, 84.0, 85.0, 106.0, 86.0, 86.0, 123.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 149.0, 150.0, 149.0, 149.0, 149.0, 150.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 59.0, 60.0, 59.0, 61.0, 59.0, 60.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 69.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 85.0, 86.0, 86.0, 86.0, 86.0, 85.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 133.0, 133.0, 134.0, 132.0, 132.0, 132.0, 218.0, 139.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 180.0, 181.0, 180.0, 184.0, 182.0, 179.0, 186.0, 178.0, 178.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 72.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1555.0000000000002, "alertThreshold": 2.0}]} 10:56:38 INFO - Return code: 0 10:56:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:56:38 INFO - # TBPL SUCCESS # 10:56:38 INFO - Running post-action listener: _resource_record_post_action 10:56:38 INFO - [mozharness: 2017-01-09 18:56:38.625722Z] Finished run-tests step (success) 10:56:38 INFO - Running post-run listener: _resource_record_post_run 10:56:38 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382849024; Read time: 12; Write time: 190956 10:56:38 INFO - TinderboxPrint: CPU usage
13.1% 10:56:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:56:38 INFO - TinderboxPrint: I/O write bytes / time
382,849,024 / 190,956 10:56:38 INFO - TinderboxPrint: CPU idle
8,330.7 (86.9%) 10:56:38 INFO - TinderboxPrint: CPU user
1,212.9 (12.7%) 10:56:38 INFO - TinderboxPrint: Swap in / out
0 / 0 10:56:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99614720; Read time: 0; Write time: 69784 10:56:38 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 282841088; Read time: 0; Write time: 121148 10:56:39 INFO - Running post-run listener: _upload_blobber_files 10:56:39 INFO - Blob upload gear active. 10:56:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:56:39 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 10:56:39 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'] 10:56:39 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 10:56:39 INFO - (blobuploader) - INFO - Open directory for files ... 10:56:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:56:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:56:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:40 INFO - (blobuploader) - INFO - Done attempting. 10:56:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:56:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:56:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:41 INFO - (blobuploader) - INFO - Done attempting. 10:56:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:56:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:56:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:44 INFO - (blobuploader) - INFO - Done attempting. 10:56:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:56:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:56:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:45 INFO - (blobuploader) - INFO - Done attempting. 10:56:45 INFO - (blobuploader) - INFO - Iteration through files over. 10:56:45 INFO - Return code: 0 10:56:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:56:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:56:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/48fbcd08b268dda3ccf2a5a74a1199823e3856c3e55c434a4eb05c5a20740e2f85278fc5872778b9c2686aa741b15b8f29ecd2b05291ce7b33ab6fd9510e4976", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d82ebafda99cf71a7f654f1cf1e49f3ef74c93cf23786bef6c530d9d9e17e36e53a6fa8a4529fc8842cb24ca66864595c823fb667642aaf26235a3d6e05cb85c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/586b4afd2d9afd923a526cb0234eb3b8f04407011e59e8682a06f19128d39274d0c78a276c35a436a99169d590349c2b4c3d2be5af34a47353559bbb0936b448", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6dbee17d654e8ea8ffb905b5ed737c9a25c66083b0a5889d8e4e508b0ea9ba5102d92abc89f36af87df534c96571cf9c8c0f5194c854755db6df975c4925169f"} 10:56:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:56:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:56:45 INFO - Contents: 10:56:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/48fbcd08b268dda3ccf2a5a74a1199823e3856c3e55c434a4eb05c5a20740e2f85278fc5872778b9c2686aa741b15b8f29ecd2b05291ce7b33ab6fd9510e4976", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d82ebafda99cf71a7f654f1cf1e49f3ef74c93cf23786bef6c530d9d9e17e36e53a6fa8a4529fc8842cb24ca66864595c823fb667642aaf26235a3d6e05cb85c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/586b4afd2d9afd923a526cb0234eb3b8f04407011e59e8682a06f19128d39274d0c78a276c35a436a99169d590349c2b4c3d2be5af34a47353559bbb0936b448", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6dbee17d654e8ea8ffb905b5ed737c9a25c66083b0a5889d8e4e508b0ea9ba5102d92abc89f36af87df534c96571cf9c8c0f5194c854755db6df975c4925169f"} 10:56:45 INFO - Running post-run listener: copy_logs_to_upload_dir 10:56:45 INFO - Copying logs to upload dir... 10:56:45 INFO - mkdir: /builds/slave/test/build/upload/logs 10:56:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.073140 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-01-09 10:56:44.240219) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 10:56:44.243566) ========= 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=6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/48fbcd08b268dda3ccf2a5a74a1199823e3856c3e55c434a4eb05c5a20740e2f85278fc5872778b9c2686aa741b15b8f29ecd2b05291ce7b33ab6fd9510e4976", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d82ebafda99cf71a7f654f1cf1e49f3ef74c93cf23786bef6c530d9d9e17e36e53a6fa8a4529fc8842cb24ca66864595c823fb667642aaf26235a3d6e05cb85c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/586b4afd2d9afd923a526cb0234eb3b8f04407011e59e8682a06f19128d39274d0c78a276c35a436a99169d590349c2b4c3d2be5af34a47353559bbb0936b448", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6dbee17d654e8ea8ffb905b5ed737c9a25c66083b0a5889d8e4e508b0ea9ba5102d92abc89f36af87df534c96571cf9c8c0f5194c854755db6df975c4925169f"} build_url:https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011627 build_url: 'https://queue.taskcluster.net/v1/task/fq3JH72uR5-a7iRVwfvp_Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/48fbcd08b268dda3ccf2a5a74a1199823e3856c3e55c434a4eb05c5a20740e2f85278fc5872778b9c2686aa741b15b8f29ecd2b05291ce7b33ab6fd9510e4976", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d82ebafda99cf71a7f654f1cf1e49f3ef74c93cf23786bef6c530d9d9e17e36e53a6fa8a4529fc8842cb24ca66864595c823fb667642aaf26235a3d6e05cb85c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/586b4afd2d9afd923a526cb0234eb3b8f04407011e59e8682a06f19128d39274d0c78a276c35a436a99169d590349c2b4c3d2be5af34a47353559bbb0936b448", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6dbee17d654e8ea8ffb905b5ed737c9a25c66083b0a5889d8e4e508b0ea9ba5102d92abc89f36af87df534c96571cf9c8c0f5194c854755db6df975c4925169f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 10:56:44.268777) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:56:44.269108) ========= 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=6dbc10da6d83fee962c624be000001a8-1483986055.480092-2113774370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:56:44.354345) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 10:56:44.354735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 10:56:44.355083) ========= ========= Total master_lag: 0.16 =========