builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1484165320.48 results: success (0) revision: 46c114f308065118917e7ae164eb4a646a113df1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.484146) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.484589) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.484858) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.498912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.499175) ========= 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=db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-11 12:08:40-- 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% 38.3M=0s 2017-01-11 12:08:40 (38.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110096 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.622634) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.622936) ========= 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=db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020051 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.671968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:40.672259) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 46c114f308065118917e7ae164eb4a646a113df1 --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 46c114f308065118917e7ae164eb4a646a113df1 --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=db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-11 12:08:40,923 truncating revision to first 12 chars 2017-01-11 12:08:40,923 Setting DEBUG logging. 2017-01-11 12:08:40,923 attempt 1/10 2017-01-11 12:08:40,924 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/46c114f30806?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 12:08:41,272 unpacking tar archive at: graphics-46c114f30806/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642950 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:41.327779) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:41.328106) ========= script_repo_revision: 46c114f308065118917e7ae164eb4a646a113df1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:41.328513) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:41.328780) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 12:08:41.337418) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-11 12:08:41.337728) ========= /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=db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:08:41 INFO - MultiFileLogger online at 20170111 12:08:41 in /builds/slave/test 12:08:41 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 12:08:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:41 INFO - {'append_to_log': False, 12:08:41 INFO - 'base_work_dir': '/builds/slave/test', 12:08:41 INFO - 'blob_upload_branch': 'Graphics', 12:08:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:41 INFO - 'branch': 'Graphics', 12:08:41 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:41 INFO - 'config_files': ('talos/linux_config.py',), 12:08:41 INFO - 'default_actions': ('clobber', 12:08:41 INFO - 'read-buildbot-config', 12:08:41 INFO - 'download-and-extract', 12:08:41 INFO - 'populate-webroot', 12:08:41 INFO - 'create-virtualenv', 12:08:41 INFO - 'install', 12:08:41 INFO - 'run-tests'), 12:08:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:41 INFO - 'download_minidump_stackwalk': True, 12:08:41 INFO - 'download_symbols': 'ondemand', 12:08:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:41 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:41 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:41 INFO - 'installer_path': 'installer.exe', 12:08:41 INFO - 'log_level': 'info', 12:08:41 INFO - 'log_name': 'talos', 12:08:41 INFO - 'log_to_console': True, 12:08:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:08:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:08:41 INFO - 'opt_config_files': (), 12:08:41 INFO - 'pip_index': False, 12:08:41 INFO - 'sps_profile': False, 12:08:41 INFO - 'sps_profile_interval': 0, 12:08:41 INFO - 'suite': 'dromaeojs', 12:08:41 INFO - 'system_bits': '32', 12:08:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:41 INFO - 'title': 'talos-linux64-ix-031', 12:08:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:41 INFO - 'use_talos_json': True, 12:08:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:41 INFO - 'work_dir': 'build'} 12:08:41 INFO - [mozharness: 2017-01-11 20:08:41.685036Z] Running clobber step. 12:08:41 INFO - Running pre-action listener: _resource_record_pre_action 12:08:41 INFO - Running main action method: clobber 12:08:41 INFO - rmtree: /builds/slave/test/build 12:08:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:42 INFO - Running post-action listener: _resource_record_post_action 12:08:42 INFO - [mozharness: 2017-01-11 20:08:42.145893Z] Finished clobber step (success) 12:08:42 INFO - [mozharness: 2017-01-11 20:08:42.145993Z] Running read-buildbot-config step. 12:08:42 INFO - Running pre-action listener: _resource_record_pre_action 12:08:42 INFO - Running main action method: read_buildbot_config 12:08:42 INFO - Using buildbot properties: 12:08:42 INFO - { 12:08:42 INFO - "project": "", 12:08:42 INFO - "product": "firefox", 12:08:42 INFO - "who": "rhunt@eqrion.net", 12:08:42 INFO - "installer_path": "public/build/target.tar.bz2", 12:08:42 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 12:08:42 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 12:08:42 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 12:08:42 INFO - "stage_platform": "linux64", 12:08:42 INFO - "basedir": "/builds/slave/test", 12:08:42 INFO - "buildnumber": 12, 12:08:42 INFO - "platform": "ubuntu64_hw", 12:08:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:08:42 INFO - "slavebuilddir": "test", 12:08:42 INFO - "taskId": "P75PTSCmQ3-SrOWFuHNYTw", 12:08:42 INFO - "branch": "graphics", 12:08:42 INFO - "script_repo_revision": "default", 12:08:42 INFO - "revision": "46c114f308065118917e7ae164eb4a646a113df1", 12:08:42 INFO - "slavename": "talos-linux64-ix-031", 12:08:42 INFO - "repo_path": "projects/graphics" 12:08:42 INFO - } 12:08:42 INFO - Finding installer, test and symbols from parent task. 12:08:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P75PTSCmQ3-SrOWFuHNYTw'}, attempt #1 12:08:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P75PTSCmQ3-SrOWFuHNYTw'}, attempt #1 12:08:43 INFO - Task dependencies: TEyG4_AIQ1uLn9aetAd9rw 12:08:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw'}, attempt #1 12:08:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2 12:08:43 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/test_packages.json 12:08:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.crashreporter-symbols.zip 12:08:43 INFO - Running post-action listener: _resource_record_post_action 12:08:43 INFO - [mozharness: 2017-01-11 20:08:43.597754Z] Finished read-buildbot-config step (success) 12:08:43 INFO - [mozharness: 2017-01-11 20:08:43.597974Z] Running download-and-extract step. 12:08:43 INFO - Running pre-action listener: _resource_record_pre_action 12:08:43 INFO - Running main action method: download_and_extract 12:08:43 INFO - mkdir: /builds/slave/test/build/tests 12:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:43 INFO - trying https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.test_packages.json 12:08:43 INFO - Downloading https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:08:44 INFO - Downloaded 896 bytes. 12:08:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:08:44 INFO - Using the following test package requirements: 12:08:44 INFO - {u'common': [u'target.common.tests.zip'], 12:08:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:08:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:08:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:08:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:08:44 INFO - u'mozbase': [u'target.common.tests.zip'], 12:08:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:08:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:08:44 INFO - u'web-platform': [u'target.common.tests.zip', 12:08:44 INFO - u'target.web-platform.tests.zip'], 12:08:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:08:44 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:08:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.common.tests.zip 12:08:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:08:44 INFO - Fetch https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.common.tests.zip into memory 12:08:44 INFO - Expected file size: 20257948 12:08:44 INFO - Obtained file size: 20257948 12:08:48 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:08:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.common.tests.zip 12:08:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:08:48 INFO - Fetch https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.common.tests.zip into memory 12:08:49 INFO - Expected file size: 20257948 12:08:49 INFO - Obtained file size: 20257948 12:08:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.talos.tests.zip 12:08:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:08:54 INFO - Fetch https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.talos.tests.zip into memory 12:08:54 INFO - Expected file size: 14403529 12:08:54 INFO - Obtained file size: 14403529 12:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:55 INFO - trying https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2 12:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:08:56 INFO - Downloaded 56061563 bytes. 12:08:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2 12:08:56 INFO - mkdir: /builds/slave/test/properties 12:08:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:56 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:56 INFO - Contents: 12:08:56 INFO - build_url:https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2 12:08:56 INFO - Running post-action listener: _resource_record_post_action 12:08:56 INFO - Running post-action listener: set_extra_try_arguments 12:08:56 INFO - [mozharness: 2017-01-11 20:08:56.096958Z] Finished download-and-extract step (success) 12:08:56 INFO - [mozharness: 2017-01-11 20:08:56.097055Z] Running populate-webroot step. 12:08:56 INFO - Running pre-action listener: _resource_record_pre_action 12:08:56 INFO - Running main action method: populate_webroot 12:08:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:56 INFO - '--tppagecycles', 12:08:56 INFO - '7'], 12:08:56 INFO - 'tests': ['tsvgm']}}, 12:08:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:08:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['damp', 'tps']}, 12:08:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'tests': ['damp', 'tps']}, 12:08:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['dromaeo_dom']}, 12:08:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['basic_compositor_video']}, 12:08:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:08:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tsvgx', 12:08:56 INFO - 'tsvgr_opacity', 12:08:56 INFO - 'tart', 12:08:56 INFO - 'tscrollx', 12:08:56 INFO - 'cart', 12:08:56 INFO - 'tsvg_static']}, 12:08:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:56 INFO - 'tsvgr_opacity', 12:08:56 INFO - 'tart', 12:08:56 INFO - 'tscrollx', 12:08:56 INFO - 'cart', 12:08:56 INFO - 'tsvg_static']}, 12:08:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tp5o']}, 12:08:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:08:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s', 12:08:56 INFO - '--xperf_path', 12:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:56 INFO - 'tests': ['tp5n']}, 12:08:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--xperf_path', 12:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:56 INFO - 'tests': ['tp5n']}}, 12:08:56 INFO - 'talos.zip': {'path': '', 12:08:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:56 INFO - Running post-action listener: _resource_record_post_action 12:08:56 INFO - [mozharness: 2017-01-11 20:08:56.110174Z] Finished populate-webroot step (success) 12:08:56 INFO - [mozharness: 2017-01-11 20:08:56.110268Z] Running create-virtualenv step. 12:08:56 INFO - Running pre-action listener: _resource_record_pre_action 12:08:56 INFO - Running main action method: create_virtualenv 12:08:56 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:56 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 12:08:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:56 INFO - Using real prefix '/usr' 12:08:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:56 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:58 INFO - Installing pip.................done. 12:08:58 INFO - Return code: 0 12:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:08:58 INFO - Reading from file tmpfile_stdout 12:08:58 INFO - Output received: 12:08:58 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) 12:08:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:58 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:08:58 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 12:08:58 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 12:08:58 INFO - Using env: {'DISPLAY': ':0', 12:08:58 INFO - 'HOME': '/home/cltbld', 12:08:58 INFO - 'LANG': 'en_US.UTF-8', 12:08:58 INFO - 'LANGUAGE': 'en_US:en', 12:08:58 INFO - 'LOGNAME': 'cltbld', 12:08:58 INFO - 'MAIL': '/var/mail/cltbld', 12:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:58 INFO - 'MOZ_NO_REMOTE': '1', 12:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:58 INFO - 'NO_EM_RESTART': '1', 12:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:58 INFO - 'PWD': '/builds/slave/test', 12:08:58 INFO - 'SHELL': '/bin/bash', 12:08:58 INFO - 'SHLVL': '1', 12:08:58 INFO - 'TERM': 'linux', 12:08:58 INFO - 'TMOUT': '86400', 12:08:58 INFO - 'USER': 'cltbld', 12:08:58 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:58 INFO - '_': '/tools/buildbot/bin/python'} 12:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:59 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 12:08:59 INFO - Cleaning up... 12:08:59 INFO - Return code: 0 12:08:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:08:59 INFO - Using env: {'DISPLAY': ':0', 12:08:59 INFO - 'HOME': '/home/cltbld', 12:08:59 INFO - 'LANG': 'en_US.UTF-8', 12:08:59 INFO - 'LANGUAGE': 'en_US:en', 12:08:59 INFO - 'LOGNAME': 'cltbld', 12:08:59 INFO - 'MAIL': '/var/mail/cltbld', 12:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:59 INFO - 'MOZ_NO_REMOTE': '1', 12:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:59 INFO - 'NO_EM_RESTART': '1', 12:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:59 INFO - 'PWD': '/builds/slave/test', 12:08:59 INFO - 'SHELL': '/bin/bash', 12:08:59 INFO - 'SHLVL': '1', 12:08:59 INFO - 'TERM': 'linux', 12:08:59 INFO - 'TMOUT': '86400', 12:08:59 INFO - 'USER': 'cltbld', 12:08:59 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:59 INFO - '_': '/tools/buildbot/bin/python'} 12:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:59 INFO - Downloading/unpacking psutil>=3.1.1 12:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:01 INFO - Installing collected packages: psutil 12:09:01 INFO - Running setup.py install for psutil 12:09:01 INFO - building 'psutil._psutil_linux' extension 12:09:01 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 12:09:01 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 12:09:01 INFO - building 'psutil._psutil_posix' extension 12:09:01 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 12:09:01 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 12:09:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:01 INFO - Successfully installed psutil 12:09:01 INFO - Cleaning up... 12:09:02 INFO - Return code: 0 12:09:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:09:02 INFO - Using env: {'DISPLAY': ':0', 12:09:02 INFO - 'HOME': '/home/cltbld', 12:09:02 INFO - 'LANG': 'en_US.UTF-8', 12:09:02 INFO - 'LANGUAGE': 'en_US:en', 12:09:02 INFO - 'LOGNAME': 'cltbld', 12:09:02 INFO - 'MAIL': '/var/mail/cltbld', 12:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:02 INFO - 'MOZ_NO_REMOTE': '1', 12:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:02 INFO - 'NO_EM_RESTART': '1', 12:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:02 INFO - 'PWD': '/builds/slave/test', 12:09:02 INFO - 'SHELL': '/bin/bash', 12:09:02 INFO - 'SHLVL': '1', 12:09:02 INFO - 'TERM': 'linux', 12:09:02 INFO - 'TMOUT': '86400', 12:09:02 INFO - 'USER': 'cltbld', 12:09:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:02 INFO - '_': '/tools/buildbot/bin/python'} 12:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:04 INFO - Installing collected packages: mozsystemmonitor 12:09:04 INFO - Running setup.py install for mozsystemmonitor 12:09:04 INFO - Successfully installed mozsystemmonitor 12:09:04 INFO - Cleaning up... 12:09:04 INFO - Return code: 0 12:09:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09:04 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')]} 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:04 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')]} 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:04 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:04 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 12:09:04 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 12:09:04 INFO - Using env: {'DISPLAY': ':0', 12:09:04 INFO - 'HOME': '/home/cltbld', 12:09:04 INFO - 'LANG': 'en_US.UTF-8', 12:09:04 INFO - 'LANGUAGE': 'en_US:en', 12:09:04 INFO - 'LOGNAME': 'cltbld', 12:09:04 INFO - 'MAIL': '/var/mail/cltbld', 12:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:04 INFO - 'MOZ_NO_REMOTE': '1', 12:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:04 INFO - 'NO_EM_RESTART': '1', 12:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:04 INFO - 'PWD': '/builds/slave/test', 12:09:04 INFO - 'SHELL': '/bin/bash', 12:09:04 INFO - 'SHLVL': '1', 12:09:04 INFO - 'TERM': 'linux', 12:09:04 INFO - 'TMOUT': '86400', 12:09:04 INFO - 'USER': 'cltbld', 12:09:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:04 INFO - '_': '/tools/buildbot/bin/python'} 12:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:04 INFO - Downloading/unpacking jsonschema==2.5.1 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09:06 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 12:09:06 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 12:09:06 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 12:09:06 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 12:09:06 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 12:09:06 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 12:09:06 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:06 INFO - warning: no files found matching '*.txt' 12:09:06 INFO - no previously-included directories found matching 'build' 12:09:06 INFO - no previously-included directories found matching 'dist' 12:09:06 INFO - no previously-included directories found matching '.git*' 12:09:06 INFO - Installing collected packages: jsonschema, functools32 12:09:07 INFO - Running setup.py install for functools32 12:09:07 INFO - warning: no files found matching '*.txt' 12:09:07 INFO - no previously-included directories found matching 'build' 12:09:07 INFO - no previously-included directories found matching 'dist' 12:09:07 INFO - no previously-included directories found matching '.git*' 12:09:07 INFO - Successfully installed jsonschema functools32 12:09:07 INFO - Cleaning up... 12:09:07 INFO - Return code: 0 12:09:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09:07 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')]} 12:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:07 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')]} 12:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:07 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:07 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 12:09:07 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 12:09:07 INFO - Using env: {'DISPLAY': ':0', 12:09:07 INFO - 'HOME': '/home/cltbld', 12:09:07 INFO - 'LANG': 'en_US.UTF-8', 12:09:07 INFO - 'LANGUAGE': 'en_US:en', 12:09:07 INFO - 'LOGNAME': 'cltbld', 12:09:07 INFO - 'MAIL': '/var/mail/cltbld', 12:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:07 INFO - 'MOZ_NO_REMOTE': '1', 12:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:07 INFO - 'NO_EM_RESTART': '1', 12:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:07 INFO - 'PWD': '/builds/slave/test', 12:09:07 INFO - 'SHELL': '/bin/bash', 12:09:07 INFO - 'SHLVL': '1', 12:09:07 INFO - 'TERM': 'linux', 12:09:07 INFO - 'TMOUT': '86400', 12:09:07 INFO - 'USER': 'cltbld', 12:09:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:07 INFO - '_': '/tools/buildbot/bin/python'} 12:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:07 INFO - Cleaning up... 12:09:07 INFO - Return code: 0 12:09:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09:07 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')]} 12:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:07 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')]} 12:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:07 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:07 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 12:09:07 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 12:09:07 INFO - Using env: {'DISPLAY': ':0', 12:09:07 INFO - 'HOME': '/home/cltbld', 12:09:07 INFO - 'LANG': 'en_US.UTF-8', 12:09:07 INFO - 'LANGUAGE': 'en_US:en', 12:09:07 INFO - 'LOGNAME': 'cltbld', 12:09:07 INFO - 'MAIL': '/var/mail/cltbld', 12:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:07 INFO - 'MOZ_NO_REMOTE': '1', 12:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:07 INFO - 'NO_EM_RESTART': '1', 12:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:07 INFO - 'PWD': '/builds/slave/test', 12:09:07 INFO - 'SHELL': '/bin/bash', 12:09:07 INFO - 'SHLVL': '1', 12:09:07 INFO - 'TERM': 'linux', 12:09:07 INFO - 'TMOUT': '86400', 12:09:07 INFO - 'USER': 'cltbld', 12:09:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:07 INFO - '_': '/tools/buildbot/bin/python'} 12:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:07 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:07 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 12:09:07 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 12:09:07 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 12:09:07 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 12:09:07 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 12:09:07 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 12:09:09 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:09 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 12:09:09 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 12:09:09 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 12:09:09 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 12:09:09 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 12:09:09 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 12:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:10 INFO - Downloading docopt-0.6.1.tar.gz 12:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:10 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:10 INFO - Running setup.py install for blobuploader 12:09:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:10 INFO - Running setup.py install for requests 12:09:10 INFO - Running setup.py install for docopt 12:09:10 INFO - Successfully installed blobuploader requests docopt 12:09:10 INFO - Cleaning up... 12:09:11 INFO - Return code: 0 12:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:11 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:11 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 12:09:11 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 12:09:11 INFO - Using env: {'DISPLAY': ':0', 12:09:11 INFO - 'HOME': '/home/cltbld', 12:09:11 INFO - 'LANG': 'en_US.UTF-8', 12:09:11 INFO - 'LANGUAGE': 'en_US:en', 12:09:11 INFO - 'LOGNAME': 'cltbld', 12:09:11 INFO - 'MAIL': '/var/mail/cltbld', 12:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:11 INFO - 'MOZ_NO_REMOTE': '1', 12:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:11 INFO - 'NO_EM_RESTART': '1', 12:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:11 INFO - 'PWD': '/builds/slave/test', 12:09:11 INFO - 'SHELL': '/bin/bash', 12:09:11 INFO - 'SHLVL': '1', 12:09:11 INFO - 'TERM': 'linux', 12:09:11 INFO - 'TMOUT': '86400', 12:09:11 INFO - 'USER': 'cltbld', 12:09:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:11 INFO - '_': '/tools/buildbot/bin/python'} 12:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:11 INFO - Running setup.py (path:/tmp/pip-epXprk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:11 INFO - Running setup.py (path:/tmp/pip-hvNSR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:11 INFO - Running setup.py (path:/tmp/pip-iG2i8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:11 INFO - Running setup.py (path:/tmp/pip-3wkBTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:11 INFO - Running setup.py (path:/tmp/pip-ciERIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:11 INFO - Running setup.py (path:/tmp/pip-ZV55SG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:11 INFO - Running setup.py (path:/tmp/pip-y4qH1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:11 INFO - Running setup.py (path:/tmp/pip-oiGlEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:11 INFO - Running setup.py (path:/tmp/pip-UwOyzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:11 INFO - Running setup.py (path:/tmp/pip-slGcYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:11 INFO - Running setup.py (path:/tmp/pip-_PihFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:12 INFO - Running setup.py (path:/tmp/pip-bd8jGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:12 INFO - Running setup.py (path:/tmp/pip-HsQZjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:12 INFO - Running setup.py (path:/tmp/pip-OUitvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:12 INFO - Running setup.py (path:/tmp/pip-T4kz8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:12 INFO - Running setup.py (path:/tmp/pip-SPZNIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:12 INFO - Running setup.py (path:/tmp/pip-tQiGqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:12 INFO - Running setup.py install for manifestparser 12:09:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:12 INFO - Running setup.py install for mozcrash 12:09:12 INFO - Running setup.py install for mozdebug 12:09:12 INFO - Running setup.py install for mozdevice 12:09:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:12 INFO - Running setup.py install for mozfile 12:09:13 INFO - Running setup.py install for mozhttpd 12:09:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozinfo 12:09:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozInstall 12:09:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozleak 12:09:13 INFO - Running setup.py install for mozlog 12:09:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for moznetwork 12:09:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozprocess 12:09:13 INFO - Running setup.py install for mozprofile 12:09:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozrunner 12:09:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozscreenshot 12:09:14 INFO - Running setup.py install for moztest 12:09:14 INFO - Running setup.py install for mozversion 12:09:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:14 INFO - Cleaning up... 12:09:14 INFO - Return code: 0 12:09:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:14 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')]} 12:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:14 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')]} 12:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:14 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:14 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 12:09:14 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 12:09:14 INFO - Using env: {'DISPLAY': ':0', 12:09:14 INFO - 'HOME': '/home/cltbld', 12:09:14 INFO - 'LANG': 'en_US.UTF-8', 12:09:14 INFO - 'LANGUAGE': 'en_US:en', 12:09:14 INFO - 'LOGNAME': 'cltbld', 12:09:14 INFO - 'MAIL': '/var/mail/cltbld', 12:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:14 INFO - 'MOZ_NO_REMOTE': '1', 12:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:14 INFO - 'NO_EM_RESTART': '1', 12:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:14 INFO - 'PWD': '/builds/slave/test', 12:09:14 INFO - 'SHELL': '/bin/bash', 12:09:14 INFO - 'SHLVL': '1', 12:09:14 INFO - 'TERM': 'linux', 12:09:14 INFO - 'TMOUT': '86400', 12:09:14 INFO - 'USER': 'cltbld', 12:09:14 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:14 INFO - '_': '/tools/buildbot/bin/python'} 12:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:14 INFO - Running setup.py (path:/tmp/pip-eb_Qq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:14 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)) 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:14 INFO - Running setup.py (path:/tmp/pip-TX7Iu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:14 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)) 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:14 INFO - Running setup.py (path:/tmp/pip-_JpJbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:14 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)) 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:14 INFO - Running setup.py (path:/tmp/pip-EcGAee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:14 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)) 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:14 INFO - Running setup.py (path:/tmp/pip-jc4uPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:14 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)) 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:14 INFO - Running setup.py (path:/tmp/pip-eprmF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:14 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)) 12:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:14 INFO - Running setup.py (path:/tmp/pip-w4NaNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:15 INFO - Running setup.py (path:/tmp/pip-KMccV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:15 INFO - Running setup.py (path:/tmp/pip-ezt_Yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:15 INFO - Running setup.py (path:/tmp/pip-c6ANGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:15 INFO - Running setup.py (path:/tmp/pip-mYcJzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:15 INFO - Running setup.py (path:/tmp/pip-TNP3BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:15 INFO - Running setup.py (path:/tmp/pip-1KJSD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:15 INFO - Running setup.py (path:/tmp/pip-3O5eVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:15 INFO - Running setup.py (path:/tmp/pip-5wIlM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:15 INFO - Running setup.py (path:/tmp/pip-JgnS_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:15 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)) 12:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:15 INFO - Running setup.py (path:/tmp/pip-txSLHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:15 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)) 12:09:15 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)) 12:09:15 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)) 12:09:15 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)) 12:09:15 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)) 12:09:15 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)) 12:09:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:15 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 12:09:15 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 12:09:15 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 12:09:15 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 12:09:15 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 12:09:15 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 12:09:17 INFO - Downloading blessings-1.6.tar.gz 12:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:17 INFO - Installing collected packages: blessings 12:09:17 INFO - Running setup.py install for blessings 12:09:17 INFO - Successfully installed blessings 12:09:17 INFO - Cleaning up... 12:09:18 INFO - Return code: 0 12:09:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:18 INFO - Reading from file tmpfile_stdout 12:09:18 INFO - Current package versions: 12:09:18 INFO - argparse == 1.2.1 12:09:18 INFO - blessings == 1.6 12:09:18 INFO - blobuploader == 1.2.4 12:09:18 INFO - docopt == 0.6.1 12:09:18 INFO - functools32 == 3.2.3-2 12:09:18 INFO - jsonschema == 2.5.1 12:09:18 INFO - manifestparser == 1.1 12:09:18 INFO - mozInstall == 1.12 12:09:18 INFO - mozcrash == 1.0 12:09:18 INFO - mozdebug == 0.1 12:09:18 INFO - mozdevice == 0.49 12:09:18 INFO - mozfile == 1.2 12:09:18 INFO - mozhttpd == 0.7 12:09:18 INFO - mozinfo == 0.9 12:09:18 INFO - mozleak == 0.1 12:09:18 INFO - mozlog == 3.4 12:09:18 INFO - moznetwork == 0.27 12:09:18 INFO - mozprocess == 0.24 12:09:18 INFO - mozprofile == 0.28 12:09:18 INFO - mozrunner == 6.13 12:09:18 INFO - mozscreenshot == 0.1 12:09:18 INFO - mozsystemmonitor == 0.3 12:09:18 INFO - moztest == 0.8 12:09:18 INFO - mozversion == 1.4 12:09:18 INFO - psutil == 3.1.1 12:09:18 INFO - requests == 1.2.3 12:09:18 INFO - wsgiref == 0.1.2 12:09:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:18 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')]} 12:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:18 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')]} 12:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:18 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:18 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 12:09:18 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 12:09:18 INFO - Using env: {'DISPLAY': ':0', 12:09:18 INFO - 'HOME': '/home/cltbld', 12:09:18 INFO - 'LANG': 'en_US.UTF-8', 12:09:18 INFO - 'LANGUAGE': 'en_US:en', 12:09:18 INFO - 'LOGNAME': 'cltbld', 12:09:18 INFO - 'MAIL': '/var/mail/cltbld', 12:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:18 INFO - 'MOZ_NO_REMOTE': '1', 12:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:18 INFO - 'NO_EM_RESTART': '1', 12:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:18 INFO - 'PWD': '/builds/slave/test', 12:09:18 INFO - 'SHELL': '/bin/bash', 12:09:18 INFO - 'SHLVL': '1', 12:09:18 INFO - 'TERM': 'linux', 12:09:18 INFO - 'TMOUT': '86400', 12:09:18 INFO - 'USER': 'cltbld', 12:09:18 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:18 INFO - '_': '/tools/buildbot/bin/python'} 12:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 INFO - Cleaning up... 12:09:18 INFO - Return code: 0 12:09:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:18 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')]} 12:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:18 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')]} 12:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:18 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 0x1dff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2170c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', '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 12:09:18 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 12:09:18 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 12:09:18 INFO - Using env: {'DISPLAY': ':0', 12:09:18 INFO - 'HOME': '/home/cltbld', 12:09:18 INFO - 'LANG': 'en_US.UTF-8', 12:09:18 INFO - 'LANGUAGE': 'en_US:en', 12:09:18 INFO - 'LOGNAME': 'cltbld', 12:09:18 INFO - 'MAIL': '/var/mail/cltbld', 12:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:18 INFO - 'MOZ_NO_REMOTE': '1', 12:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:18 INFO - 'NO_EM_RESTART': '1', 12:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:18 INFO - 'PWD': '/builds/slave/test', 12:09:18 INFO - 'SHELL': '/bin/bash', 12:09:18 INFO - 'SHLVL': '1', 12:09:18 INFO - 'TERM': 'linux', 12:09:18 INFO - 'TMOUT': '86400', 12:09:18 INFO - 'USER': 'cltbld', 12:09:18 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:18 INFO - '_': '/tools/buildbot/bin/python'} 12:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:18 INFO - Cleaning up... 12:09:18 INFO - Return code: 0 12:09:18 INFO - Running post-action listener: _resource_record_post_action 12:09:18 INFO - Running post-action listener: _start_resource_monitoring 12:09:18 INFO - Starting resource monitoring. 12:09:18 INFO - [mozharness: 2017-01-11 20:09:18.780569Z] Finished create-virtualenv step (success) 12:09:18 INFO - [mozharness: 2017-01-11 20:09:18.781135Z] Running install step. 12:09:18 INFO - Running pre-action listener: _resource_record_pre_action 12:09:18 INFO - Running main action method: install 12:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:18 INFO - Reading from file tmpfile_stdout 12:09:18 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:19 INFO - Reading from file tmpfile_stdout 12:09:19 INFO - Output received: 12:09:19 INFO - Usage: mozinstall [options] installer 12:09:19 INFO - Options: 12:09:19 INFO - -h, --help show this help message and exit 12:09:19 INFO - -d DEST, --destination=DEST 12:09:19 INFO - Directory to install application into. [default: 12:09:19 INFO - "/builds/slave/test"] 12:09:19 INFO - --app=APP Application being installed. [default: firefox] 12:09:19 INFO - mkdir: /builds/slave/test/build/application 12:09:19 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'] 12:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:09:35 INFO - Reading from file tmpfile_stdout 12:09:35 INFO - Output received: 12:09:35 INFO - /builds/slave/test/build/application/firefox/firefox 12:09:35 INFO - Running post-action listener: _resource_record_post_action 12:09:35 INFO - [mozharness: 2017-01-11 20:09:35.280563Z] Finished install step (success) 12:09:35 INFO - [mozharness: 2017-01-11 20:09:35.280910Z] Running run-tests step. 12:09:35 INFO - Running pre-action listener: _resource_record_pre_action 12:09:35 INFO - Running main action method: run_tests 12:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:35 INFO - Python 2.7.3 12:09:35 INFO - Return code: 0 12:09:35 INFO - grabbing minidump binary from tooltool 12:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:35 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 0x216e3f0>, '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 0x216ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216f1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:35 INFO - Return code: 0 12:09:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:09:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:09:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:35 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/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 12:09:35 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/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 12:09:35 INFO - Using env: {'DISPLAY': ':0', 12:09:35 INFO - 'HOME': '/home/cltbld', 12:09:35 INFO - 'LANG': 'en_US.UTF-8', 12:09:35 INFO - 'LANGUAGE': 'en_US:en', 12:09:35 INFO - 'LOGNAME': 'cltbld', 12:09:35 INFO - 'MAIL': '/var/mail/cltbld', 12:09:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:35 INFO - 'MOZ_NO_REMOTE': '1', 12:09:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:35 INFO - 'NO_EM_RESTART': '1', 12:09:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:35 INFO - 'PWD': '/builds/slave/test', 12:09:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:35 INFO - 'SHELL': '/bin/bash', 12:09:35 INFO - 'SHLVL': '1', 12:09:35 INFO - 'TERM': 'linux', 12:09:35 INFO - 'TMOUT': '86400', 12:09:35 INFO - 'USER': 'cltbld', 12:09:35 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189', 12:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:35 INFO - '_': '/tools/buildbot/bin/python'} 12:09:35 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/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 12:09:35 INFO - mozversion application_buildid: 20170111174727 12:09:35 INFO - mozversion application_changeset: 46c114f308065118917e7ae164eb4a646a113df1 12:09:35 INFO - mozversion application_display_name: Nightly 12:09:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:35 INFO - mozversion application_name: Firefox 12:09:35 INFO - mozversion application_remotingname: firefox 12:09:35 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:09:35 INFO - mozversion application_vendor: Mozilla 12:09:35 INFO - mozversion application_version: 53.0a1 12:09:35 INFO - mozversion platform_buildid: 20170111174727 12:09:35 INFO - mozversion platform_changeset: 46c114f308065118917e7ae164eb4a646a113df1 12:09:35 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:09:35 INFO - mozversion platform_version: 53.0a1 12:09:35 INFO - using testdate: 1484165375 12:09:35 INFO - actual date: 1484165375 12:09:35 INFO - starting webserver on 'localhost:53117' 12:09:35 INFO - SUITE-START | Running 2 tests 12:09:35 INFO - TEST-START | dromaeo_css 12:09:35 INFO - operating with platform_type : linux_ 12:09:35 INFO - Initialising browser for dromaeo_css test... 12:09:35 INFO - TEST-INFO | started process 14191 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpohCyav/profile http://localhost:53117/getInfo.html) 12:09:39 INFO - PROCESS | 14191 | __metrics Screen width/height:1600/1200 12:09:39 INFO - PROCESS | 14191 | colorDepth:24 12:09:39 INFO - PROCESS | 14191 | Browser inner width/height: 1024/697 12:09:39 INFO - PROCESS | 14191 | __metrics 12:09:40 INFO - PROCESS | 14191 | 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" 12:09:40 INFO - PROCESS | 14191 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:09:40 INFO - PROCESS | 14191 | console.error: PushService: 12:09:40 INFO - PROCESS | 14191 | stateChangeProcessEnqueue: Error transitioning state 12:09:40 INFO - PROCESS | 14191 | UnknownError 12:09:41 INFO - TEST-INFO | 14191: exit 0 12:09:41 INFO - Browser initialized. 12:09:41 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:41 INFO - TEST-INFO | started process 14270 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpohCyav/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:53 INFO - PROCESS | 14270 | RSS: Main: 152231936 12:09:53 INFO - PROCESS | 14270 | 12:12:58 INFO - PROCESS | 14270 | Cycle 1(1): loaded http://localhost:53117/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53117/tests/dromaeo/cssquery-ext.html) 12:13:00 INFO - PROCESS | 14270 | RSS: Main: 362201088 12:13:00 INFO - PROCESS | 14270 | 12:14:42 INFO - PROCESS | 14270 | ************************* 12:14:42 INFO - PROCESS | 14270 | A coding exception was thrown and uncaught in a Task. 12:14:42 INFO - PROCESS | 14270 | 12:14:42 INFO - PROCESS | 14270 | Full message: ReferenceError: fetch is not defined 12:14:42 INFO - PROCESS | 14270 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:14:42 INFO - PROCESS | 14270 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:14:42 INFO - PROCESS | 14270 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:14:42 INFO - PROCESS | 14270 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:14:42 INFO - PROCESS | 14270 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:14:42 INFO - PROCESS | 14270 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:14:42 INFO - PROCESS | 14270 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:14:42 INFO - PROCESS | 14270 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:14:42 INFO - PROCESS | 14270 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:14:42 INFO - PROCESS | 14270 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:14:42 INFO - PROCESS | 14270 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:14:42 INFO - PROCESS | 14270 | 12:14:42 INFO - PROCESS | 14270 | ************************* 12:16:03 INFO - PROCESS | 14270 | Cycle 1(1): loaded http://localhost:53117/tests/dromaeo/cssquery-ext.html (next: http://localhost:53117/tests/dromaeo/cssquery-jquery.html) 12:16:03 INFO - PROCESS | 14270 | RSS: Main: 245907456 12:16:03 INFO - PROCESS | 14270 | 12:19:05 INFO - PROCESS | 14270 | Cycle 1(1): loaded http://localhost:53117/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53117/tests/dromaeo/cssquery-mootools.html) 12:19:06 INFO - PROCESS | 14270 | RSS: Main: 186474496 12:19:06 INFO - PROCESS | 14270 | 12:22:08 INFO - PROCESS | 14270 | Cycle 1(1): loaded http://localhost:53117/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53117/tests/dromaeo/cssquery-prototype.html) 12:22:09 INFO - PROCESS | 14270 | RSS: Main: 209711104 12:22:09 INFO - PROCESS | 14270 | 12:25:12 INFO - PROCESS | 14270 | Cycle 1(1): loaded http://localhost:53117/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53117/tests/dromaeo/cssquery-yui.html) 12:25:13 INFO - PROCESS | 14270 | RSS: Main: 186871808 12:25:13 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | Cycle 1(1): loaded http://localhost:53117/tests/dromaeo/cssquery-yui.html (next: http://localhost:53117/tests/dromaeo/cssquery-dojo.html) 12:28:16 INFO - PROCESS | 14270 | RSS: Main: 267649024 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | __start_tp_report 12:28:16 INFO - PROCESS | 14270 | _x_x_mozilla_page_load 12:28:16 INFO - PROCESS | 14270 | _x_x_mozilla_page_load_details 12:28:16 INFO - PROCESS | 14270 | |i|pagename|runs| 12:28:16 INFO - PROCESS | 14270 | |0;dojo.html;2593.792496600272;2612.8432294062345;2634.538955682755;2645.88093535791;2646.166457565868;4718.669693121483;4745.335653008573;4823.662343635942;4859.829905953288;4880.780364883584;4796.160671882424;4836.58888994435;4883.584895283905;4908.87727806805;4951.257311403291;4297.656187504992;4316.438862947813;4331.935020974688;4402.3176407656865;4457.264551349023;4054.4795701611897;4383.101464199832;4463.152001119777;4489.483709373431;4549.590536851682;4338.1540599583095;4358.4987726411555;4443.80002899867;4451.376807246988;4529.6376289896725;4308.870733877958;4328.91342173158;4330.307150855848;4382.758948257844;4406.0747243078995;4293.742375457483;4301.333293339538;4316.179925814085;4320.503142138663;4358.498772641124;4654.394928659252;4693.24908014716;4700.106979673851;4702.177119004161;4709.434867815884;5086.287919691209;5104.412992505872;5127.948720512784;5146.742662866879;5161.638685292031;5136.203888397305;5175.663581867204;5176.637635365526;5180.585553155738;5228;4854.708717476928;4856.68431551948;4869.172240719086;4877.317175595419;4916.532929371704;5106.978604279159;5132.204508301219;5139.12634852076;5185.040767458032;5300.44345343741;5021;5087.211482220262;5094.057599344133;5097.158968770112;5098.490150984871;5011;5049.419316778544;5103.948960510422;5113.514216149534;5164.457731938168;5005.874853128701;5050.4697006814495;5077.416097148801;5077.898442031139;5128.512791284822;5084.56781173597;5085.5168758968575;5089.083964886282;5091.745412729349;5150.098732721759;4275.764832934218;4317.784110794448;4323.308270676676;4348.847790327387;4352.129574085196;3700.0934770980743;3708.2583483303447;3726.292004519187;3741.7942013189536;3780.8487660493274;4358;4369.148016136823;4369.890752731207;4373.803178856959;4401.559844015637;5117.334746483008;5121.74391280442;5127.512886275797;5136.614753893473;5165.66423182492;4280.293751530962;4298.505671847777;4307.181635489247;4323.783810809447;4325.286327755885;4534.433195850519;4560.931586026212;4562.634989200922;4589.541045895384;4595.747233902168;4741.644376671763;4746.762661866962;4759.690620109682;4764.952350476451;4791.089692958327;4991.5008499151245;5049.495050494921;5060.240963855451;5109.616778741683;5118.41138269089;4751.097291514601;4770.260609605517;4792.041591681608;4792.376990991149;4810.470848206624;5033.6476446648385;5038.420581633159;5039.672421292605;5044.016416798689;5046.394432668103;5098.974505127599;5118.232265160255;5123.436421993652;5128.564072053917;5139.126348520836;4990.600751939909;4992.400911890596;5001.549860512571;5003.199488081889;5003.3495645565845;4930.5069493050405;4991.750412479434;4993.775280112331;5001.124803159505;5001.474845141208;4897.41231052276;4905.754712264301;4919.065377578248;4947.727874966911;4984.675996060245;5028.773705183203;5064.34163558735;5066.08810414129;5073.11220536412;5112.872178195426;4845.563899249085;4883.80464781419;4891.73095479752;4952.083864485082;4999.67502112376 12:28:16 INFO - PROCESS | 14270 | |1;ext.html;539.3123767196823;574.198992405594;576.7202906590303;584.9970750146249;594.6432140715568;39090.21819563611;41188.794056029714;41508.58491415085;45045.549544504545;45288;41912.16175676485;42353.576464235346;42357.57642423575;42405.36391954123;42452.78773606128;7428;7585.431092668058;7629.618519074052;7746.767596972071;7748.612569371537;10005.54975026122;10249.077583017526;10282.537285822156;10627.521761520711;10631.681049568486;10091.344062635942;10141.746456338577;10150.340227885164;10150.3402278852;10151.492425378738;10185.541650625739;10238.488075596228;10244.897551024469;10246.231532635007;10259.589616415335;9897.90102098977;9973.35173213746;9975.850362244571;9977.600895964153;9981.90018099817;38227.42658860119;38601.03497412559;38631.613683863085;38737.61262387397;38742.61257387418;3463.168839478507;3513.0865974846724;3521.5245941797784;3529.4529347951107;3557.964420355789;28082;28609.284767880763;28627.57058644122;28644.14067577976;28661.28346791326;3451.6375780543185;3451.758376913617;3662.10278481771;3671.6695497405362;3696.2607478504406;250313;250990;253523;254348;254609;53828.46171538313;54037.704909837455;54213.999999999214;54271.45728542743;54370.64073398118;57436.425635743944;57463.13805292924;57535.71232143813;57796.71101644465;57834.42165578374;14076.859231407554;14160.36278367476;14319.78520322196;14366;14371.2095834728;9987.250956178315;10418.635347762944;11193.440327983568;11230.438478076227;11292.774144517063;18789.24843006292;19326.425637054952;19473.415797526097;19916.50208744764;20028.799712002692;7349.448791340671;7534.284242996907;7616.0860696715645;7633.969414129133;7734.806629834299;831.5177197225595;832.92087251711;833.9041010283773;853.7310747114639;857.5412154497438;28306.292342691184;29116.85441572821;29141.854290728836;29678.55482167769;29718;945.8864936207699;960.1502670136979;960.3085778239656;971.2521358554015;1102.1568500097433;14138.575842724735;15017.774733379008;15156.696866062617;15244.31400586954;15642.843571564366;374811;380634;380891;382359;383316;45712;46037.539624603996;46106;46141.30788038182;46176.3073553897;1191.2792760380019;1222.2971791220084;1228.7358217983176;1237.1216436330305;1246.501399440213;25884.48231035331;26679.19962401131;27068.864655676596;27156;27808.721912781424;40231.59768402279;40855.795721021204;40878.59121408748;40907.38638920359;40912;269717;335538;335835;336993;337080;24450.14424495134;24511.264662060166;24551.140710075062;24554.14060507945;24651.63022554699;35968.460473093444;36143.27713445769;36554.26891462209;37208.44187337246;37293.254134916606;31901;32189.517157242193;34196.48705269373;34963;36763;32592.511112333807;32712.50931236081;32860.507092393156;33883.45874059406;36137.277254455286;32651.18372040718;40354.39468408035;40523.39214911838;41692.37461438141;42810.14379712326;35123.29753404867;38761;39605.60394396019;39740.602593973694;39954.60045399625;29423.26441838971;31162.532562012042;31504.36991260116;31530.684693152776;31942 12:28:16 INFO - PROCESS | 14270 | |2;jquery.html;371.3909188930157;388.5182373856417;391.915738116305;392.60150946789;423.7923417525412;13141.408636611352;17654.47036588901;17958.730619040707;19025.809741902576;19179.32872349468;15781;16601.169941502907;16959.32162713493;17086.31654733809;18191;4813.061453016672;4966.453690094087;4990.351254336932;4998.650094493387;5004.674696144757;3699.3341198584244;3865.5747867734526;4878.780454879539;4958.87602809929;4960.107180707471;5502.889942201154;5745.224394706703;5774.711264436783;5869.325027621814;5870.618409803349;5643.266375371217;5708.457696518824;5743.282089738783;5837.066069428893;5857.443542863442;5630.774769009235;5637;5638.971805140948;5641.548676105902;5642.181883626851;13227.801582976166;16139.43511977087;20453.897730511402;21179.47051323714;21232.753449310047;2158.6222411077997;2166.750823655284;2224.288227767115;2259.649754288088;2318.7217533895873;9687.079727425884;9775.90224097757;9814.754631134207;10322.174226061898;10372.688819335432;1756.4116021132936;1760.6742752590812;1764.5588602849289;1771.5571107223195;1800.9819901801077;80387.5980620099;81750;81800;83739.5813020937;85729.14270857273;10568.260221784554;10876.619318323821;11002.50488727993;11049.834252486058;11154.163437742201;15290.159041252615;15298.617534561503;15308.540743777705;15310.540683779505;15320.846791532165;7301.780946571611;7500.324970752658;7515.774526764206;7543.321101100927;7604.277593628596;6931.757388491378;6974;7127.037849890302;7154.534955227893;7155.39179169766;4525.841595544141;4660.883477913011;4675.111728771522;4764.880877978078;4800.879978000508;1908.5228692826793;1982.87784976727;1984.930527431533;2010.3667344786345;2030.1879248300502;759.7454852624322;778.7040924448786;787.8581855266107;805.5046146619786;808.4058217210346;17357.47927562175;17722.822771772375;17748.645027099385;18019.639607207784;18393.908030459763;777.6500574741389;782.5304817109688;784.744957888677;787.5274835099009;812.2811311988933;9657.32398732096;9858.852117218104;9934.403935763878;10009.249306302056;10038.698839034842;480460;481544;484537;515538;516081;91262;91896.99999999866;91965.54017229872;92133.53933230291;94393;802.3100133884855;819.0989911097745;821.0188824354839;823.0164952881249;828.1718281718402;15034.548963531111;15212.695746085237;15283.388664453298;15383.153926534149;15819.129947852978;37941.430878536295;38641.80679096699;38910.47081939665;39130.6086939127;39724;52482.47517524776;52483.47516524786;52601.473985259654;54969.68454936682;55442.44557554373;7792.376609871083;7888.408369372205;7901.17037711032;8075.446803406482;8093.69329741021;9416.52917354116;11824.290542567473;11909.940450297692;11921.82117268259;12341;9967.609759992802;10048.799024019332;10155.187584993035;10211.489425528842;10677;9845.261605379483;10231.334963227664;10482.213833962636;10621.468926553796;10807.837882431928;10190.643327483502;10313.484325783531;10411.271211015157;10785.838212426977;10798.190135740007;7926.524408535506;8007.639656215601;8017.679292828221;8075.152109028468;8191.79520511992;7777.805554861174;10101.393916365041;10286.639967601099;10303.845442318521;10694.732631683959 12:28:16 INFO - PROCESS | 14270 | |3;mootools.html;1361.3669643615717;1362.986370136299;1381.9170849749012;1382.3226618956714;1388.5834249725087;6721.73113075477;7100.396466300358;7115.964420177905;7116.786496405116;7119.252478489761;6121.571489995702;6355.71399287032;6752.426043786284;7054.188768291648;7071.398931090864;2095.0991073838204;2152.849300548962;2153.203314773536;2155.902984365707;2164.39396968849;2110.4618322327783;2131.6802479628104;2148.731408573928;2151.6772484127428;2155.9784402156015;1809.9457016289532;1855.3320804510365;2056.444759914822;2104.9158033678636;2149.7850214978457;2133.040131940625;2136.2523116909115;2148.8066074053254;2151.5696860627936;2152.9569408611897;2100.0444797617047;2116.0689296709397;2120.533482633818;2126.914923403062;2127.319257837493;7058.929410705879;7123.394511466531;7136.179339375934;7137.2505886881645;7152.821179470503;4338.1540599583095;4465.888352791174;4466.9106617867465;4468.106378724236;4471.28459446487;2659.7473240042164;2844.5590933405147;2848.7151284871557;2904.0271509044496;2904.4917139500712;1030.7938412317492;1069.5294070608984;1097.1442275025493;1097.2154909239853;1101.5703875488566;9256;9448;9451.385659932082;9458.479783611898;9464.57409416571;3937.153511994935;4033.4554835097474;4089.672826173899;4114.238865809775;4119.8970025748995;8073.515589064675;8077.474964127313;8079.838403231903;8084.070331911787;8084.636191371284;4493.820247190141;4495.57292057254;4496.842610508616;4498.482674492475;4499.842505512292;3537.0980399998352;3643.6720695137033;3927.3716205407;3948.2103579284258;3989.3018721723583;3469.635688252697;3509.94735078974;3590.7127429805555;3605.6935160511125;3641.690456311243;1125.8254970479588;1166.3643314393676;1169.1990986174499;1171.47869198206;1179.8112302031634;844.3161039557978;844.3329769482164;848.4230723108222;876.7063033883593;876.7369789063256;4758.191107511731;4934.901301974012;4936.111499930047;5034.244863270539;5044.621653376012;558.5615291995764;570.9200711900337;571.4114462104039;585.5315747402061;586.9794557190478;3394.168428734976;3403.3193361327835;3409.9659003409647;3455.585329760445;3518.3842827505086;8086.231807978233;8356.498610083414;8365.623546940402;8454.15458454162;8455.069942306342;8327.50034997902;8337.207965243293;8338;8340.958295208606;8344.499330040217;6555.639439830759;6577.572457790228;6652.467802575879;6671.766488172988;6696.598204107671;4789.329493870791;4796.184648609675;4806.903861922672;4818.927716084191;4894.902101958012;7783.066032076187;7794.6882124714375;7806.41451891099;7865.370770338245;7878.763637090897;7827.412944029106;7902.759868453906;7990.3208227299;8042.798930026796;8043.316318113026;5619.072852979212;5789.681567513659;6164.969175154275;6286.5599408041;6292.685365731603;6394.4564712;6563.343665633399;6619;6696.029075783887;6730;6120.785772497941;6129.172561704113;6144.109104179958;6310.684465776784;6328;5913.438223368773;5971.970140149445;5985.730642121202;6002.219711437485;6004.579679422399;5468.835934921959;5550.555955523468;5553.111502159635;5555.555555555465;5561.944380556142;4257.382679511516;4264.04059086703;4269.274223381971;4320.492974221331;4444.266695995254;4193.22425351311;4193.853215137456;4253.532111467674;4354.847580334673;4401.449818772653 12:28:16 INFO - PROCESS | 14270 | |4;prototype.html;363.76173606287875;367.25997115811634;372.13664298826717;375.9266942946127;381.42024123332516;4647.186742320097;4834.975825120874;4843.878903027426;4857.222844344906;4860.902781944364;4647.674662773598;4721.598664113546;4756.453007904083;4799.064182484417;4805;3897.57126716061;3906.831708401836;3915.7846318452475;3924.0373517993944;3937.6652984496354;3759.6052414496357;3845.961590370598;3907.5115610548683;3923.2545816294955;3923.293807114718;3781.2626537825136;3814.084619691268;3814.6757525610356;3824.451097305596;3859.5754467008605;3791.734578579487;3793.2223894101644;3822.445745366906;3824.9043773905596;3828.6554210120944;3447.172678557153;3459.429194182957;3471.8090505022215;3481.3211423772373;3487.2676737885076;4614.976925115387;4672.953270467286;4691.413573303337;4729.952700472985;4754.738489383117;4393.0890062822455;4394.802233899482;4398.736075835428;4420.557944205586;4426.822927082912;5005.749712514396;5024.371953505812;5043.243513472972;5064.2403639454005;5087.923681144749;3717.8512859485586;3748.194138260287;3796.3925771876643;3810.466534685146;3819.7899115548635;5235.476452354734;5246.160614301732;5272.551833094153;5283.2075188722;5296.920546191772;3901.5708272090046;3912.4326972589383;3914.8042597869994;3923.136909879822;3927.626875446828;3991.940120898189;3992;3993.9016770388375;4009.2983727848095;4029.5567487576345;4280.186764514795;4445.488768791565;4463.174312752151;4469.620082293041;4491.371208030879;5035.521625445577;5038.3198268234055;5050.722210278396;5052.444231134572;5086.64393492452;1281.391339113932;1300.1678925487506;1337.404854839587;1364.764887776564;1371.430856194688;968.5205823117602;990.7027891632481;1002.7443002034435;1011.5953618552638;1014.8731408573929;4236.343366778155;4237.8304867804945;4270.935935960963;4275.593818587229;4276.049525827169;4963.205887058053;5017.011475876687;5027.4469808321055;5034.79860805571;5035.521625445577;4211.410402543646;4224.5353011168745;4237.5762423758;4266.274733295348;4267.743935363888;2537.771600555922;2651.912715786518;2652.7082020977678;2658.521466136114;2671.3455203475273;4664.673472856867;4693.0992947856175;4711.434627844681;4712.481627021025;4723.291506274087;4890.970247347382;5129.8717532062;5141.408737995103;5142.30578871855;5144.897102057937;4608.308753686973;4662.790174442091;4695.882602934886;4707.59985401231;4713.29300604912;4980.078685443204;4980.775865086008;5004.3994720633755;5010.672171874529;5072.340595722532;4786.899013226907;4942.555170034714;4943.876403089952;4960.826371076995;5007.649464537448;4630.675852690414;4653;4660.042782031623;4669.976650116728;4680.297955306732;3844.9231015380096;3846.6922646187677;3908.687305015535;3917.196974620139;3939.448477213135;4942.579880710036;5031.295618613324;5046.772895219798;5050.898982020265;5052.570531504716;4730.881727956691;4945.826896058621;5039.067772462106;5043.319151914445;5046.924296135488;5002.124628190125;5006.4743201963265;5019.196928491424;5032.823851165338;5067.442581316126;4776.617870570277;4921.606271498343;4925.753712314442;4967.503249675004;5004.22350065736;4995.500449954975;5059.482155353306;5085;5085.64400491962;5090.796368145381;4937.432195297587;4937.802487900443;4944.208926571875;4948.05986862495;4980.360408983244 12:28:16 INFO - PROCESS | 14270 | |5;yui.html;652.5106170372221;676.3135417551183;740.2930201657417;773.1186447449908;792.120745971971;12985.285809280485;13978.650533736649;14047.64880877976;14064.085834420768;14076.788848167285;13289.136206146593;13506;13542.526011589596;13545.525906593246;13550.796738048937;651.4950913042406;663.8738639658474;774.6978678315461;811.4684881402691;825.0841565861889;791.3866753266236;807.096052421286;807.3621838747382;807.563915485637;808.1877712898529;872.520113937335;872.5811610426999;872.9388942774009;873.1137895036511;873.1617647058831;688.1707542411432;707.2361849202848;867.453504292295;867.7396780965717;867.8047439326165;594.9018411962021;607.59898467012;611.8653896142841;612.1124369663961;612.5681394616785;13889.374978126009;13889.930550347184;13906.58280251594;13922.443102275896;13936.442542298297;13015.479380824756;13089.214647121107;13190.142640728322;13227.86772132276;13343.599692009255;11377.488013039432;11409.885901140966;11430.599929002445;11459.598914037968;11970.82043769344;4098.315876255902;4128.958710412918;4167.6457501112445;4178.206140833232;4178.498580170369;62913;63566.68216658887;64145.679271603345;65207;67139.3286067138;9865.161461275871;9905.306628536078;10313.74215644615;10351.327163734333;10519.31624444424;17408.39070632547;17482;18221.362252321105;18770.24919003252;18999.62000759977;6356.682165891687;6381.298057213703;6383.106365108889;6455.322191169954;6538.084668146464;3445.896623101311;3583;3584.408572585546;3585.8386372613295;3587.537401193865;2727.031903674167;2793.1201671473423;2803.733645303693;2811.1566530040905;2825.816321939067;488.2212870471592;491.1870853737071;491.6533843640225;498.1879536355717;501.2631431795255;600.83777380108;603.3544107804695;624.256393152094;629.9716512756937;638.9712462939179;7864.4888082274465;8178.345732341399;8230.21812927771;8632.266257368068;8678;572.4475880775072;579.0879365000142;581.0441823200811;598.4404581716109;598.8203538938301;4060.8578699745367;4181.91636167281;4213.154871897343;4250.149970006011;4313.870583882489;55935.720321398134;63947;64308;64678;69101.65449172723;58319.41680583225;59031.70484147552;59547.70226148841;60002.699986499785;63355.366446335865;51563.22655160176;51759.48240517547;52043.73978130085;52062.73968630133;52921.2061819073;7877.251661092187;8206.179382061746;8223.972003499563;8359.860354476974;8393.412461127662;36509.817450912575;36730.4490432649;38222;38273.4258986121;38701.2259754809;43474.56525434705;47008.29487557758;47056.52943470521;47811;48832.75583622059;9553.18797902186;9687.66093186735;9697.806043879224;9744;10163.898361016296;10817.45912704346;10925.890741092488;11046.447677616248;11103.666889993314;11203.775924481628;10034.147097496794;10295.897041029493;10560.366378017343;10889.891101088888;10955.616553420592;9203.907960920305;9231.076892310715;9315.254779617479;9376.249900008119;9436.905630943602;9835.21318294549;9853.802923941337;10104.69685909424;10111.24165687591;10239.232057595562;8034.8393032137865;8100.351971842357;8197.426180167331;8402.579871006303;8543.658253669784;7746.651400687096;8076;10120.898791011996;10287.664553875955;10404.791904162026 12:28:16 INFO - PROCESS | 14270 | __end_tp_report 12:28:16 INFO - PROCESS | 14270 | __start_cc_report 12:28:16 INFO - PROCESS | 14270 | _x_x_mozilla_cycle_collect,2436 12:28:16 INFO - PROCESS | 14270 | __end_cc_report 12:28:16 INFO - PROCESS | 14270 | __startTimestamp1484166496473__endTimestamp 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | ------- Summary: start ------- 12:28:16 INFO - PROCESS | 14270 | Number of tests: 6 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | [#0] dojo.html Cycles:165 Average:4716.32 Median:4883.58 stddev:512.19 (10.5%) stddev-sans-first:485.94 12:28:16 INFO - PROCESS | 14270 | Values: 2593.8 2612.8 2634.5 2645.9 2646.2 4718.7 4745.3 4823.7 4859.8 4880.8 4796.2 4836.6 4883.6 4908.9 4951.3 4297.7 4316.4 4331.9 4402.3 4457.3 4054.5 4383.1 4463.2 4489.5 4549.6 4338.2 4358.5 4443.8 4451.4 4529.6 4308.9 4328.9 4330.3 4382.8 4406.1 4293.7 4301.3 4316.2 4320.5 4358.5 4654.4 4693.2 4700.1 4702.2 4709.4 5086.3 5104.4 5127.9 5146.7 5161.6 5136.2 5175.7 5176.6 5180.6 5228.0 4854.7 4856.7 4869.2 4877.3 4916.5 5107.0 5132.2 5139.1 5185.0 5300.4 5021.0 5087.2 5094.1 5097.2 5098.5 5011.0 5049.4 5103.9 5113.5 5164.5 5005.9 5050.5 5077.4 5077.9 5128.5 5084.6 5085.5 5089.1 5091.7 5150.1 4275.8 4317.8 4323.3 4348.8 4352.1 3700.1 3708.3 3726.3 3741.8 3780.8 4358.0 4369.1 4369.9 4373.8 4401.6 5117.3 5121.7 5127.5 5136.6 5165.7 4280.3 4298.5 4307.2 4323.8 4325.3 4534.4 4560.9 4562.6 4589.5 4595.7 4741.6 4746.8 4759.7 4765.0 4791.1 4991.5 5049.5 5060.2 5109.6 5118.4 4751.1 4770.3 4792.0 4792.4 4810.5 5033.6 5038.4 5039.7 5044.0 5046.4 5099.0 5118.2 5123.4 5128.6 5139.1 4990.6 4992.4 5001.5 5003.2 5003.3 4930.5 4991.8 4993.8 5001.1 5001.5 4897.4 4905.8 4919.1 4947.7 4984.7 5028.8 5064.3 5066.1 5073.1 5112.9 4845.6 4883.8 4891.7 4952.1 4999.7 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | [#1] ext.html Cycles:180 Average:47975.45 Median:3543.71 stddev:84905.79 (2396.0%) stddev-sans-first:85069.27 12:28:16 INFO - PROCESS | 14270 | Values: 539.3 574.2 576.7 585.0 594.6 39090.2 41188.8 41508.6 45045.5 45288.0 41912.2 42353.6 42357.6 42405.4 42452.8 7428.0 7585.4 7629.6 7746.8 7748.6 10005.5 10249.1 10282.5 10627.5 10631.7 10091.3 10141.7 10150.3 10150.3 10151.5 10185.5 10238.5 10244.9 10246.2 10259.6 9897.9 9973.4 9975.9 9977.6 9981.9 38227.4 38601.0 38631.6 38737.6 38742.6 3463.2 3513.1 3521.5 3529.5 3558.0 28082.0 28609.3 28627.6 28644.1 28661.3 3451.6 3451.8 3662.1 3671.7 3696.3 250313.0 250990.0 253523.0 254348.0 254609.0 53828.5 54037.7 54214.0 54271.5 54370.6 57436.4 57463.1 57535.7 57796.7 57834.4 14076.9 14160.4 14319.8 14366.0 14371.2 9987.3 10418.6 11193.4 11230.4 11292.8 18789.2 19326.4 19473.4 19916.5 20028.8 7349.4 7534.3 7616.1 7634.0 7734.8 831.5 832.9 833.9 853.7 857.5 28306.3 29116.9 29141.9 29678.6 29718.0 945.9 960.2 960.3 971.3 1102.2 14138.6 15017.8 15156.7 15244.3 15642.8 374811.0 380634.0 380891.0 382359.0 383316.0 45712.0 46037.5 46106.0 46141.3 46176.3 1191.3 1222.3 1228.7 1237.1 1246.5 25884.5 26679.2 27068.9 27156.0 27808.7 40231.6 40855.8 40878.6 40907.4 40912.0 269717.0 335538.0 335835.0 336993.0 337080.0 24450.1 24511.3 24551.1 24554.1 24651.6 35968.5 36143.3 36554.3 37208.4 37293.3 31901.0 32189.5 34196.5 34963.0 36763.0 32592.5 32712.5 32860.5 33883.5 36137.3 32651.2 40354.4 40523.4 41692.4 42810.1 35123.3 38761.0 39605.6 39740.6 39954.6 29423.3 31162.5 31504.4 31530.7 31942.0 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | [#2] jquery.html Cycles:180 Average:28283.55 Median:4782.88 stddev:81859.44 (1711.5%) stddev-sans-first:82062.25 12:28:16 INFO - PROCESS | 14270 | Values: 371.4 388.5 391.9 392.6 423.8 13141.4 17654.5 17958.7 19025.8 19179.3 15781.0 16601.2 16959.3 17086.3 18191.0 4813.1 4966.5 4990.4 4998.7 5004.7 3699.3 3865.6 4878.8 4958.9 4960.1 5502.9 5745.2 5774.7 5869.3 5870.6 5643.3 5708.5 5743.3 5837.1 5857.4 5630.8 5637.0 5639.0 5641.5 5642.2 13227.8 16139.4 20453.9 21179.5 21232.8 2158.6 2166.8 2224.3 2259.6 2318.7 9687.1 9775.9 9814.8 10322.2 10372.7 1756.4 1760.7 1764.6 1771.6 1801.0 80387.6 81750.0 81800.0 83739.6 85729.1 10568.3 10876.6 11002.5 11049.8 11154.2 15290.2 15298.6 15308.5 15310.5 15320.8 7301.8 7500.3 7515.8 7543.3 7604.3 6931.8 6974.0 7127.0 7154.5 7155.4 4525.8 4660.9 4675.1 4764.9 4800.9 1908.5 1982.9 1984.9 2010.4 2030.2 759.7 778.7 787.9 805.5 808.4 17357.5 17722.8 17748.6 18019.6 18393.9 777.7 782.5 784.7 787.5 812.3 9657.3 9858.9 9934.4 10009.2 10038.7 480460.0 481544.0 484537.0 515538.0 516081.0 91262.0 91897.0 91965.5 92133.5 94393.0 802.3 819.1 821.0 823.0 828.2 15034.5 15212.7 15283.4 15383.2 15819.1 37941.4 38641.8 38910.5 39130.6 39724.0 52482.5 52483.5 52601.5 54969.7 55442.4 7792.4 7888.4 7901.2 8075.4 8093.7 9416.5 11824.3 11909.9 11921.8 12341.0 9967.6 10048.8 10155.2 10211.5 10677.0 9845.3 10231.3 10482.2 10621.5 10807.8 10190.6 10313.5 10411.3 10785.8 10798.2 7926.5 8007.6 8017.7 8075.2 8191.8 7777.8 10101.4 10286.6 10303.8 10694.7 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | [#3] mootools.html Cycles:180 Average:4676.61 Median:4914.90 stddev:2479.05 (50.4%) stddev-sans-first:2473.49 12:28:16 INFO - PROCESS | 14270 | Values: 1361.4 1363.0 1381.9 1382.3 1388.6 6721.7 7100.4 7116.0 7116.8 7119.3 6121.6 6355.7 6752.4 7054.2 7071.4 2095.1 2152.8 2153.2 2155.9 2164.4 2110.5 2131.7 2148.7 2151.7 2156.0 1809.9 1855.3 2056.4 2104.9 2149.8 2133.0 2136.3 2148.8 2151.6 2153.0 2100.0 2116.1 2120.5 2126.9 2127.3 7058.9 7123.4 7136.2 7137.3 7152.8 4338.2 4465.9 4466.9 4468.1 4471.3 2659.7 2844.6 2848.7 2904.0 2904.5 1030.8 1069.5 1097.1 1097.2 1101.6 9256.0 9448.0 9451.4 9458.5 9464.6 3937.2 4033.5 4089.7 4114.2 4119.9 8073.5 8077.5 8079.8 8084.1 8084.6 4493.8 4495.6 4496.8 4498.5 4499.8 3537.1 3643.7 3927.4 3948.2 3989.3 3469.6 3509.9 3590.7 3605.7 3641.7 1125.8 1166.4 1169.2 1171.5 1179.8 844.3 844.3 848.4 876.7 876.7 4758.2 4934.9 4936.1 5034.2 5044.6 558.6 570.9 571.4 585.5 587.0 3394.2 3403.3 3410.0 3455.6 3518.4 8086.2 8356.5 8365.6 8454.2 8455.1 8327.5 8337.2 8338.0 8341.0 8344.5 6555.6 6577.6 6652.5 6671.8 6696.6 4789.3 4796.2 4806.9 4818.9 4894.9 7783.1 7794.7 7806.4 7865.4 7878.8 7827.4 7902.8 7990.3 8042.8 8043.3 5619.1 5789.7 6165.0 6286.6 6292.7 6394.5 6563.3 6619.0 6696.0 6730.0 6120.8 6129.2 6144.1 6310.7 6328.0 5913.4 5972.0 5985.7 6002.2 6004.6 5468.8 5550.6 5553.1 5555.6 5561.9 4257.4 4264.0 4269.3 4320.5 4444.3 4193.2 4193.9 4253.5 4354.8 4401.4 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | [#4] prototype.html Cycles:180 Average:4188.54 Median:4663.73 stddev:1155.05 (24.8%) stddev-sans-first:1122.05 12:28:16 INFO - PROCESS | 14270 | Values: 363.8 367.3 372.1 375.9 381.4 4647.2 4835.0 4843.9 4857.2 4860.9 4647.7 4721.6 4756.5 4799.1 4805.0 3897.6 3906.8 3915.8 3924.0 3937.7 3759.6 3846.0 3907.5 3923.3 3923.3 3781.3 3814.1 3814.7 3824.5 3859.6 3791.7 3793.2 3822.4 3824.9 3828.7 3447.2 3459.4 3471.8 3481.3 3487.3 4615.0 4673.0 4691.4 4730.0 4754.7 4393.1 4394.8 4398.7 4420.6 4426.8 5005.7 5024.4 5043.2 5064.2 5087.9 3717.9 3748.2 3796.4 3810.5 3819.8 5235.5 5246.2 5272.6 5283.2 5296.9 3901.6 3912.4 3914.8 3923.1 3927.6 3991.9 3992.0 3993.9 4009.3 4029.6 4280.2 4445.5 4463.2 4469.6 4491.4 5035.5 5038.3 5050.7 5052.4 5086.6 1281.4 1300.2 1337.4 1364.8 1371.4 968.5 990.7 1002.7 1011.6 1014.9 4236.3 4237.8 4270.9 4275.6 4276.0 4963.2 5017.0 5027.4 5034.8 5035.5 4211.4 4224.5 4237.6 4266.3 4267.7 2537.8 2651.9 2652.7 2658.5 2671.3 4664.7 4693.1 4711.4 4712.5 4723.3 4891.0 5129.9 5141.4 5142.3 5144.9 4608.3 4662.8 4695.9 4707.6 4713.3 4980.1 4980.8 5004.4 5010.7 5072.3 4786.9 4942.6 4943.9 4960.8 5007.6 4630.7 4653.0 4660.0 4670.0 4680.3 3844.9 3846.7 3908.7 3917.2 3939.4 4942.6 5031.3 5046.8 5050.9 5052.6 4730.9 4945.8 5039.1 5043.3 5046.9 5002.1 5006.5 5019.2 5032.8 5067.4 4776.6 4921.6 4925.8 4967.5 5004.2 4995.5 5059.5 5085.0 5085.6 5090.8 4937.4 4937.8 4944.2 4948.1 4980.4 12:28:16 INFO - PROCESS | 14270 | 12:28:16 INFO - PROCESS | 14270 | [#5] yui.html Cycles:180 Average:14758.41 Median:28254.08 stddev:18725.21 (66.3%) stddev-sans-first:18747.78 12:28:16 INFO - PROCESS | 14270 | Values: 652.5 676.3 740.3 773.1 792.1 12985.3 13978.7 14047.6 14064.1 14076.8 13289.1 13506.0 13542.5 13545.5 13550.8 651.5 663.9 774.7 811.5 825.1 791.4 807.1 807.4 807.6 808.2 872.5 872.6 872.9 873.1 873.2 688.2 707.2 867.5 867.7 867.8 594.9 607.6 611.9 612.1 612.6 13889.4 13889.9 13906.6 13922.4 13936.4 13015.5 13089.2 13190.1 13227.9 13343.6 11377.5 11409.9 11430.6 11459.6 11970.8 4098.3 4129.0 4167.6 4178.2 4178.5 62913.0 63566.7 64145.7 65207.0 67139.3 9865.2 9905.3 10313.7 10351.3 10519.3 17408.4 17482.0 18221.4 18770.2 18999.6 6356.7 6381.3 6383.1 6455.3 6538.1 3445.9 3583.0 3584.4 3585.8 3587.5 2727.0 2793.1 2803.7 2811.2 2825.8 488.2 491.2 491.7 498.2 501.3 600.8 603.4 624.3 630.0 639.0 7864.5 8178.3 8230.2 8632.3 8678.0 572.4 579.1 581.0 598.4 598.8 4060.9 4181.9 4213.2 4250.1 4313.9 55935.7 63947.0 64308.0 64678.0 69101.7 58319.4 59031.7 59547.7 60002.7 63355.4 51563.2 51759.5 52043.7 52062.7 52921.2 7877.3 8206.2 8224.0 8359.9 8393.4 36509.8 36730.4 38222.0 38273.4 38701.2 43474.6 47008.3 47056.5 47811.0 48832.8 9553.2 9687.7 9697.8 9744.0 10163.9 10817.5 10925.9 11046.4 11103.7 11203.8 10034.1 10295.9 10560.4 10889.9 10955.6 9203.9 9231.1 9315.3 9376.2 9436.9 9835.2 9853.8 10104.7 10111.2 10239.2 8034.8 8100.4 8197.4 8402.6 8543.7 7746.7 8076.0 10120.9 10287.7 10404.8 12:28:16 INFO - PROCESS | 14270 | -------- Summary: end -------- 12:28:16 INFO - PROCESS | 14270 | 12:28:17 INFO - TEST-INFO | 14270: exit 0 12:28:17 INFO - TEST-OK | dromaeo_css | took 1121758ms 12:28:17 INFO - TEST-START | kraken 12:28:17 INFO - operating with platform_type : linux_ 12:28:17 INFO - Initialising browser for kraken test... 12:28:17 INFO - TEST-INFO | started process 14576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoGiEG0/profile http://localhost:53117/getInfo.html) 12:28:21 INFO - PROCESS | 14576 | __metrics Screen width/height:1600/1200 12:28:21 INFO - PROCESS | 14576 | colorDepth:24 12:28:21 INFO - PROCESS | 14576 | Browser inner width/height: 1024/697 12:28:21 INFO - PROCESS | 14576 | __metrics 12:28:22 INFO - PROCESS | 14576 | 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" 12:28:22 INFO - PROCESS | 14576 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:28:22 INFO - PROCESS | 14576 | console.error: PushService: 12:28:22 INFO - PROCESS | 14576 | stateChangeProcessEnqueue: Error transitioning state 12:28:22 INFO - PROCESS | 14576 | UnknownError 12:28:23 INFO - TEST-INFO | 14576: exit 0 12:28:23 INFO - Browser initialized. 12:28:23 INFO - Running cycle 1/1 for kraken test... 12:28:23 INFO - TEST-INFO | started process 14656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoGiEG0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:35 INFO - PROCESS | 14656 | RSS: Main: 152211456 12:28:35 INFO - PROCESS | 14656 | 12:28:38 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53117/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:39 INFO - PROCESS | 14656 | RSS: Main: 206446592 12:28:39 INFO - PROCESS | 14656 | 12:28:41 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53117/tests/kraken/driver.html?testName=audio-dft) 12:28:42 INFO - PROCESS | 14656 | RSS: Main: 185597952 12:28:42 INFO - PROCESS | 14656 | 12:28:45 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53117/tests/kraken/driver.html?testName=audio-fft) 12:28:46 INFO - PROCESS | 14656 | RSS: Main: 268652544 12:28:46 INFO - PROCESS | 14656 | 12:28:48 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53117/tests/kraken/driver.html?testName=audio-oscillator) 12:28:48 INFO - PROCESS | 14656 | RSS: Main: 179412992 12:28:48 INFO - PROCESS | 14656 | 12:28:51 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53117/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:51 INFO - PROCESS | 14656 | RSS: Main: 182939648 12:28:51 INFO - PROCESS | 14656 | 12:28:54 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53117/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:55 INFO - PROCESS | 14656 | RSS: Main: 222310400 12:28:55 INFO - PROCESS | 14656 | 12:28:58 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53117/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:59 INFO - PROCESS | 14656 | RSS: Main: 216887296 12:28:59 INFO - PROCESS | 14656 | 12:29:02 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53117/tests/kraken/driver.html?testName=json-parse-financial) 12:29:02 INFO - PROCESS | 14656 | RSS: Main: 218050560 12:29:02 INFO - PROCESS | 14656 | 12:29:04 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53117/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:05 INFO - PROCESS | 14656 | RSS: Main: 182521856 12:29:05 INFO - PROCESS | 14656 | 12:29:07 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:07 INFO - PROCESS | 14656 | RSS: Main: 189648896 12:29:07 INFO - PROCESS | 14656 | 12:29:09 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:10 INFO - PROCESS | 14656 | RSS: Main: 198352896 12:29:10 INFO - PROCESS | 14656 | 12:29:13 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:14 INFO - PROCESS | 14656 | RSS: Main: 207495168 12:29:14 INFO - PROCESS | 14656 | 12:29:17 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:17 INFO - PROCESS | 14656 | RSS: Main: 203816960 12:29:17 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | Cycle 1(1): loaded http://localhost:53117/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53117/tests/kraken/driver.html?testName=ai-astar) 12:29:19 INFO - PROCESS | 14656 | RSS: Main: 254402560 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | __start_tp_report 12:29:19 INFO - PROCESS | 14656 | _x_x_mozilla_page_load 12:29:19 INFO - PROCESS | 14656 | _x_x_mozilla_page_load_details 12:29:19 INFO - PROCESS | 14656 | |i|pagename|runs| 12:29:19 INFO - PROCESS | 14656 | |0;ai-astar;108;114;115;117;115;114;114;115;114;114 12:29:19 INFO - PROCESS | 14656 | |1;audio-beat-detection;153;130;128;170;122;123;123;124;149;126 12:29:19 INFO - PROCESS | 14656 | |2;audio-dft;163;161;162;161;162;162;162;162;162;162 12:29:19 INFO - PROCESS | 14656 | |3;audio-fft;77;75;76;111;76;78;76;75;107;76 12:29:19 INFO - PROCESS | 14656 | |4;audio-oscillator;85;84;112;84;84;84;99;86;85;108 12:29:19 INFO - PROCESS | 14656 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 12:29:19 INFO - PROCESS | 14656 | |6;imaging-darkroom;149;150;150;149;148;150;148;149;149;150 12:29:19 INFO - PROCESS | 14656 | |7;imaging-desaturate;95;96;95;96;95;96;96;95;96;96 12:29:19 INFO - PROCESS | 14656 | |8;json-parse-financial;61;60;60;59;61;60;60;60;60;61 12:29:19 INFO - PROCESS | 14656 | |9;json-stringify-tinderbox;67;68;67;68;67;71;68;67;68;68 12:29:19 INFO - PROCESS | 14656 | |10;stanford-crypto-aes;84;84;83;85;88;84;83;85;84;84 12:29:19 INFO - PROCESS | 14656 | |11;stanford-crypto-ccm;140;135;133;131;140;129;130;139;179;143 12:29:19 INFO - PROCESS | 14656 | |12;stanford-crypto-pbkdf2;175;180;177;178;171;175;175;176;177;178 12:29:19 INFO - PROCESS | 14656 | |13;stanford-crypto-sha256-iterative;67;69;68;69;69;69;69;68;67;68 12:29:19 INFO - PROCESS | 14656 | __end_tp_report 12:29:19 INFO - PROCESS | 14656 | __start_cc_report 12:29:19 INFO - PROCESS | 14656 | _x_x_mozilla_cycle_collect,1888 12:29:19 INFO - PROCESS | 14656 | __end_cc_report 12:29:19 INFO - PROCESS | 14656 | __startTimestamp1484166559696__endTimestamp 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | ------- Summary: start ------- 12:29:19 INFO - PROCESS | 14656 | Number of tests: 14 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.50 stddev:2.31 (2.0%) stddev-sans-first:1.00 12:29:19 INFO - PROCESS | 14656 | Values: 108.0 114.0 115.0 117.0 115.0 114.0 114.0 115.0 114.0 114.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:129.00 stddev:16.59 (12.9%) stddev-sans-first:16.24 12:29:19 INFO - PROCESS | 14656 | Values: 153.0 130.0 128.0 170.0 122.0 123.0 123.0 124.0 149.0 126.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 12:29:19 INFO - PROCESS | 14656 | Values: 163.0 161.0 162.0 161.0 162.0 162.0 162.0 162.0 162.0 162.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:13.92 (18.3%) stddev-sans-first:14.61 12:29:19 INFO - PROCESS | 14656 | Values: 77.0 75.0 76.0 111.0 76.0 78.0 76.0 75.0 107.0 76.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:84.50 stddev:10.99 (13.0%) stddev-sans-first:11.43 12:29:19 INFO - PROCESS | 14656 | Values: 85.0 84.0 112.0 84.0 84.0 84.0 99.0 86.0 85.0 108.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 12:29:19 INFO - PROCESS | 14656 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 12:29:19 INFO - PROCESS | 14656 | Values: 149.0 150.0 150.0 149.0 148.0 150.0 148.0 149.0 149.0 150.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 12:29:19 INFO - PROCESS | 14656 | Values: 95.0 96.0 95.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 12:29:19 INFO - PROCESS | 14656 | Values: 61.0 60.0 60.0 59.0 61.0 60.0 60.0 60.0 60.0 61.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.22 12:29:19 INFO - PROCESS | 14656 | Values: 67.0 68.0 67.0 68.0 67.0 71.0 68.0 67.0 68.0 68.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 12:29:19 INFO - PROCESS | 14656 | Values: 84.0 84.0 83.0 85.0 88.0 84.0 83.0 85.0 84.0 84.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:139.50 stddev:14.56 (10.4%) stddev-sans-first:15.44 12:29:19 INFO - PROCESS | 14656 | Values: 140.0 135.0 133.0 131.0 140.0 129.0 130.0 139.0 179.0 143.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:177.00 stddev:2.44 (1.4%) stddev-sans-first:2.55 12:29:19 INFO - PROCESS | 14656 | Values: 175.0 180.0 177.0 178.0 171.0 175.0 175.0 176.0 177.0 178.0 12:29:19 INFO - PROCESS | 14656 | 12:29:19 INFO - PROCESS | 14656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.73 12:29:19 INFO - PROCESS | 14656 | Values: 67.0 69.0 68.0 69.0 69.0 69.0 69.0 68.0 67.0 68.0 12:29:19 INFO - PROCESS | 14656 | -------- Summary: end -------- 12:29:19 INFO - PROCESS | 14656 | 12:29:20 INFO - TEST-INFO | 14656: exit 0 12:29:20 INFO - TEST-OK | kraken | took 63525ms 12:29:20 INFO - SUITE-END | took 1185s 12:29:21 INFO - Completed test suite (00:19:45) 12:29:21 INFO - javascript benchmark 12:29:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2593.792496600272, 2612.8432294062345, 2634.538955682755, 2645.88093535791, 2646.166457565868, 4718.669693121483, 4745.335653008573, 4823.662343635942, 4859.829905953288, 4880.780364883584, 4796.160671882424, 4836.58888994435, 4883.584895283905, 4908.87727806805, 4951.257311403291, 4297.656187504992, 4316.438862947813, 4331.935020974688, 4402.3176407656865, 4457.264551349023, 4054.4795701611897, 4383.101464199832, 4463.152001119777, 4489.483709373431, 4549.590536851682, 4338.1540599583095, 4358.4987726411555, 4443.80002899867, 4451.376807246988, 4529.6376289896725, 4308.870733877958, 4328.91342173158, 4330.307150855848, 4382.758948257844, 4406.0747243078995, 4293.742375457483, 4301.333293339538, 4316.179925814085, 4320.503142138663, 4358.498772641124, 4654.394928659252, 4693.24908014716, 4700.106979673851, 4702.177119004161, 4709.434867815884, 5086.287919691209, 5104.412992505872, 5127.948720512784, 5146.742662866879, 5161.638685292031, 5136.203888397305, 5175.663581867204, 5176.637635365526, 5180.585553155738, 5228.0, 4854.708717476928, 4856.68431551948, 4869.172240719086, 4877.317175595419, 4916.532929371704, 5106.978604279159, 5132.204508301219, 5139.12634852076, 5185.040767458032, 5300.44345343741, 5021.0, 5087.211482220262, 5094.057599344133, 5097.158968770112, 5098.490150984871, 5011.0, 5049.419316778544, 5103.948960510422, 5113.514216149534, 5164.457731938168, 5005.874853128701, 5050.4697006814495, 5077.416097148801, 5077.898442031139, 5128.512791284822, 5084.56781173597, 5085.5168758968575, 5089.083964886282, 5091.745412729349, 5150.098732721759, 4275.764832934218, 4317.784110794448, 4323.308270676676, 4348.847790327387, 4352.129574085196, 3700.0934770980743, 3708.2583483303447, 3726.292004519187, 3741.7942013189536, 3780.8487660493274, 4358.0, 4369.148016136823, 4369.890752731207, 4373.803178856959, 4401.559844015637, 5117.334746483008, 5121.74391280442, 5127.512886275797, 5136.614753893473, 5165.66423182492, 4280.293751530962, 4298.505671847777, 4307.181635489247, 4323.783810809447, 4325.286327755885, 4534.433195850519, 4560.931586026212, 4562.634989200922, 4589.541045895384, 4595.747233902168, 4741.644376671763, 4746.762661866962, 4759.690620109682, 4764.952350476451, 4791.089692958327, 4991.5008499151245, 5049.495050494921, 5060.240963855451, 5109.616778741683, 5118.41138269089, 4751.097291514601, 4770.260609605517, 4792.041591681608, 4792.376990991149, 4810.470848206624, 5033.6476446648385, 5038.420581633159, 5039.672421292605, 5044.016416798689, 5046.394432668103, 5098.974505127599, 5118.232265160255, 5123.436421993652, 5128.564072053917, 5139.126348520836, 4990.600751939909, 4992.400911890596, 5001.549860512571, 5003.199488081889, 5003.3495645565845, 4930.5069493050405, 4991.750412479434, 4993.775280112331, 5001.124803159505, 5001.474845141208, 4897.41231052276, 4905.754712264301, 4919.065377578248, 4947.727874966911, 4984.675996060245, 5028.773705183203, 5064.34163558735, 5066.08810414129, 5073.11220536412, 5112.872178195426, 4845.563899249085, 4883.80464781419, 4891.73095479752, 4952.083864485082, 4999.67502112376], "value": 4682.374345523163, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.3123767196823, 574.198992405594, 576.7202906590303, 584.9970750146249, 594.6432140715568, 39090.21819563611, 41188.794056029714, 41508.58491415085, 45045.549544504545, 45288.0, 41912.16175676485, 42353.576464235346, 42357.57642423575, 42405.36391954123, 42452.78773606128, 7428.0, 7585.431092668058, 7629.618519074052, 7746.767596972071, 7748.612569371537, 10005.54975026122, 10249.077583017526, 10282.537285822156, 10627.521761520711, 10631.681049568486, 10091.344062635942, 10141.746456338577, 10150.340227885164, 10150.3402278852, 10151.492425378738, 10185.541650625739, 10238.488075596228, 10244.897551024469, 10246.231532635007, 10259.589616415335, 9897.90102098977, 9973.35173213746, 9975.850362244571, 9977.600895964153, 9981.90018099817, 38227.42658860119, 38601.03497412559, 38631.613683863085, 38737.61262387397, 38742.61257387418, 3463.168839478507, 3513.0865974846724, 3521.5245941797784, 3529.4529347951107, 3557.964420355789, 28082.0, 28609.284767880763, 28627.57058644122, 28644.14067577976, 28661.28346791326, 3451.6375780543185, 3451.758376913617, 3662.10278481771, 3671.6695497405362, 3696.2607478504406, 250313.0, 250990.0, 253523.0, 254348.0, 254609.0, 53828.46171538313, 54037.704909837455, 54213.999999999214, 54271.45728542743, 54370.64073398118, 57436.425635743944, 57463.13805292924, 57535.71232143813, 57796.71101644465, 57834.42165578374, 14076.859231407554, 14160.36278367476, 14319.78520322196, 14366.0, 14371.2095834728, 9987.250956178315, 10418.635347762944, 11193.440327983568, 11230.438478076227, 11292.774144517063, 18789.24843006292, 19326.425637054952, 19473.415797526097, 19916.50208744764, 20028.799712002692, 7349.448791340671, 7534.284242996907, 7616.0860696715645, 7633.969414129133, 7734.806629834299, 831.5177197225595, 832.92087251711, 833.9041010283773, 853.7310747114639, 857.5412154497438, 28306.292342691184, 29116.85441572821, 29141.854290728836, 29678.55482167769, 29718.0, 945.8864936207699, 960.1502670136979, 960.3085778239656, 971.2521358554015, 1102.1568500097433, 14138.575842724735, 15017.774733379008, 15156.696866062617, 15244.31400586954, 15642.843571564366, 374811.0, 380634.0, 380891.0, 382359.0, 383316.0, 45712.0, 46037.539624603996, 46106.0, 46141.30788038182, 46176.3073553897, 1191.2792760380019, 1222.2971791220084, 1228.7358217983176, 1237.1216436330305, 1246.501399440213, 25884.48231035331, 26679.19962401131, 27068.864655676596, 27156.0, 27808.721912781424, 40231.59768402279, 40855.795721021204, 40878.59121408748, 40907.38638920359, 40912.0, 269717.0, 335538.0, 335835.0, 336993.0, 337080.0, 24450.14424495134, 24511.264662060166, 24551.140710075062, 24554.14060507945, 24651.63022554699, 35968.460473093444, 36143.27713445769, 36554.26891462209, 37208.44187337246, 37293.254134916606, 31901.0, 32189.517157242193, 34196.48705269373, 34963.0, 36763.0, 32592.511112333807, 32712.50931236081, 32860.507092393156, 33883.45874059406, 36137.277254455286, 32651.18372040718, 40354.39468408035, 40523.39214911838, 41692.37461438141, 42810.14379712326, 35123.29753404867, 38761.0, 39605.60394396019, 39740.602593973694, 39954.60045399625, 29423.26441838971, 31162.532562012042, 31504.36991260116, 31530.684693152776, 31942.0], "value": 18311.028159637004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.3909188930157, 388.5182373856417, 391.915738116305, 392.60150946789, 423.7923417525412, 13141.408636611352, 17654.47036588901, 17958.730619040707, 19025.809741902576, 19179.32872349468, 15781.0, 16601.169941502907, 16959.32162713493, 17086.31654733809, 18191.0, 4813.061453016672, 4966.453690094087, 4990.351254336932, 4998.650094493387, 5004.674696144757, 3699.3341198584244, 3865.5747867734526, 4878.780454879539, 4958.87602809929, 4960.107180707471, 5502.889942201154, 5745.224394706703, 5774.711264436783, 5869.325027621814, 5870.618409803349, 5643.266375371217, 5708.457696518824, 5743.282089738783, 5837.066069428893, 5857.443542863442, 5630.774769009235, 5637.0, 5638.971805140948, 5641.548676105902, 5642.181883626851, 13227.801582976166, 16139.43511977087, 20453.897730511402, 21179.47051323714, 21232.753449310047, 2158.6222411077997, 2166.750823655284, 2224.288227767115, 2259.649754288088, 2318.7217533895873, 9687.079727425884, 9775.90224097757, 9814.754631134207, 10322.174226061898, 10372.688819335432, 1756.4116021132936, 1760.6742752590812, 1764.5588602849289, 1771.5571107223195, 1800.9819901801077, 80387.5980620099, 81750.0, 81800.0, 83739.5813020937, 85729.14270857273, 10568.260221784554, 10876.619318323821, 11002.50488727993, 11049.834252486058, 11154.163437742201, 15290.159041252615, 15298.617534561503, 15308.540743777705, 15310.540683779505, 15320.846791532165, 7301.780946571611, 7500.324970752658, 7515.774526764206, 7543.321101100927, 7604.277593628596, 6931.757388491378, 6974.0, 7127.037849890302, 7154.534955227893, 7155.39179169766, 4525.841595544141, 4660.883477913011, 4675.111728771522, 4764.880877978078, 4800.879978000508, 1908.5228692826793, 1982.87784976727, 1984.930527431533, 2010.3667344786345, 2030.1879248300502, 759.7454852624322, 778.7040924448786, 787.8581855266107, 805.5046146619786, 808.4058217210346, 17357.47927562175, 17722.822771772375, 17748.645027099385, 18019.639607207784, 18393.908030459763, 777.6500574741389, 782.5304817109688, 784.744957888677, 787.5274835099009, 812.2811311988933, 9657.32398732096, 9858.852117218104, 9934.403935763878, 10009.249306302056, 10038.698839034842, 480460.0, 481544.0, 484537.0, 515538.0, 516081.0, 91262.0, 91896.99999999866, 91965.54017229872, 92133.53933230291, 94393.0, 802.3100133884855, 819.0989911097745, 821.0188824354839, 823.0164952881249, 828.1718281718402, 15034.548963531111, 15212.695746085237, 15283.388664453298, 15383.153926534149, 15819.129947852978, 37941.430878536295, 38641.80679096699, 38910.47081939665, 39130.6086939127, 39724.0, 52482.47517524776, 52483.47516524786, 52601.473985259654, 54969.68454936682, 55442.44557554373, 7792.376609871083, 7888.408369372205, 7901.17037711032, 8075.446803406482, 8093.69329741021, 9416.52917354116, 11824.290542567473, 11909.940450297692, 11921.82117268259, 12341.0, 9967.609759992802, 10048.799024019332, 10155.187584993035, 10211.489425528842, 10677.0, 9845.261605379483, 10231.334963227664, 10482.213833962636, 10621.468926553796, 10807.837882431928, 10190.643327483502, 10313.484325783531, 10411.271211015157, 10785.838212426977, 10798.190135740007, 7926.524408535506, 8007.639656215601, 8017.679292828221, 8075.152109028468, 8191.79520511992, 7777.805554861174, 10101.393916365041, 10286.639967601099, 10303.845442318521, 10694.732631683959], "value": 8469.530025125521, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.3669643615717, 1362.986370136299, 1381.9170849749012, 1382.3226618956714, 1388.5834249725087, 6721.73113075477, 7100.396466300358, 7115.964420177905, 7116.786496405116, 7119.252478489761, 6121.571489995702, 6355.71399287032, 6752.426043786284, 7054.188768291648, 7071.398931090864, 2095.0991073838204, 2152.849300548962, 2153.203314773536, 2155.902984365707, 2164.39396968849, 2110.4618322327783, 2131.6802479628104, 2148.731408573928, 2151.6772484127428, 2155.9784402156015, 1809.9457016289532, 1855.3320804510365, 2056.444759914822, 2104.9158033678636, 2149.7850214978457, 2133.040131940625, 2136.2523116909115, 2148.8066074053254, 2151.5696860627936, 2152.9569408611897, 2100.0444797617047, 2116.0689296709397, 2120.533482633818, 2126.914923403062, 2127.319257837493, 7058.929410705879, 7123.394511466531, 7136.179339375934, 7137.2505886881645, 7152.821179470503, 4338.1540599583095, 4465.888352791174, 4466.9106617867465, 4468.106378724236, 4471.28459446487, 2659.7473240042164, 2844.5590933405147, 2848.7151284871557, 2904.0271509044496, 2904.4917139500712, 1030.7938412317492, 1069.5294070608984, 1097.1442275025493, 1097.2154909239853, 1101.5703875488566, 9256.0, 9448.0, 9451.385659932082, 9458.479783611898, 9464.57409416571, 3937.153511994935, 4033.4554835097474, 4089.672826173899, 4114.238865809775, 4119.8970025748995, 8073.515589064675, 8077.474964127313, 8079.838403231903, 8084.070331911787, 8084.636191371284, 4493.820247190141, 4495.57292057254, 4496.842610508616, 4498.482674492475, 4499.842505512292, 3537.0980399998352, 3643.6720695137033, 3927.3716205407, 3948.2103579284258, 3989.3018721723583, 3469.635688252697, 3509.94735078974, 3590.7127429805555, 3605.6935160511125, 3641.690456311243, 1125.8254970479588, 1166.3643314393676, 1169.1990986174499, 1171.47869198206, 1179.8112302031634, 844.3161039557978, 844.3329769482164, 848.4230723108222, 876.7063033883593, 876.7369789063256, 4758.191107511731, 4934.901301974012, 4936.111499930047, 5034.244863270539, 5044.621653376012, 558.5615291995764, 570.9200711900337, 571.4114462104039, 585.5315747402061, 586.9794557190478, 3394.168428734976, 3403.3193361327835, 3409.9659003409647, 3455.585329760445, 3518.3842827505086, 8086.231807978233, 8356.498610083414, 8365.623546940402, 8454.15458454162, 8455.069942306342, 8327.50034997902, 8337.207965243293, 8338.0, 8340.958295208606, 8344.499330040217, 6555.639439830759, 6577.572457790228, 6652.467802575879, 6671.766488172988, 6696.598204107671, 4789.329493870791, 4796.184648609675, 4806.903861922672, 4818.927716084191, 4894.902101958012, 7783.066032076187, 7794.6882124714375, 7806.41451891099, 7865.370770338245, 7878.763637090897, 7827.412944029106, 7902.759868453906, 7990.3208227299, 8042.798930026796, 8043.316318113026, 5619.072852979212, 5789.681567513659, 6164.969175154275, 6286.5599408041, 6292.685365731603, 6394.4564712, 6563.343665633399, 6619.0, 6696.029075783887, 6730.0, 6120.785772497941, 6129.172561704113, 6144.109104179958, 6310.684465776784, 6328.0, 5913.438223368773, 5971.970140149445, 5985.730642121202, 6002.219711437485, 6004.579679422399, 5468.835934921959, 5550.555955523468, 5553.111502159635, 5555.555555555465, 5561.944380556142, 4257.382679511516, 4264.04059086703, 4269.274223381971, 4320.492974221331, 4444.266695995254, 4193.22425351311, 4193.853215137456, 4253.532111467674, 4354.847580334673, 4401.449818772653], "value": 3831.0438554329703, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.76173606287875, 367.25997115811634, 372.13664298826717, 375.9266942946127, 381.42024123332516, 4647.186742320097, 4834.975825120874, 4843.878903027426, 4857.222844344906, 4860.902781944364, 4647.674662773598, 4721.598664113546, 4756.453007904083, 4799.064182484417, 4805.0, 3897.57126716061, 3906.831708401836, 3915.7846318452475, 3924.0373517993944, 3937.6652984496354, 3759.6052414496357, 3845.961590370598, 3907.5115610548683, 3923.2545816294955, 3923.293807114718, 3781.2626537825136, 3814.084619691268, 3814.6757525610356, 3824.451097305596, 3859.5754467008605, 3791.734578579487, 3793.2223894101644, 3822.445745366906, 3824.9043773905596, 3828.6554210120944, 3447.172678557153, 3459.429194182957, 3471.8090505022215, 3481.3211423772373, 3487.2676737885076, 4614.976925115387, 4672.953270467286, 4691.413573303337, 4729.952700472985, 4754.738489383117, 4393.0890062822455, 4394.802233899482, 4398.736075835428, 4420.557944205586, 4426.822927082912, 5005.749712514396, 5024.371953505812, 5043.243513472972, 5064.2403639454005, 5087.923681144749, 3717.8512859485586, 3748.194138260287, 3796.3925771876643, 3810.466534685146, 3819.7899115548635, 5235.476452354734, 5246.160614301732, 5272.551833094153, 5283.2075188722, 5296.920546191772, 3901.5708272090046, 3912.4326972589383, 3914.8042597869994, 3923.136909879822, 3927.626875446828, 3991.940120898189, 3992.0, 3993.9016770388375, 4009.2983727848095, 4029.5567487576345, 4280.186764514795, 4445.488768791565, 4463.174312752151, 4469.620082293041, 4491.371208030879, 5035.521625445577, 5038.3198268234055, 5050.722210278396, 5052.444231134572, 5086.64393492452, 1281.391339113932, 1300.1678925487506, 1337.404854839587, 1364.764887776564, 1371.430856194688, 968.5205823117602, 990.7027891632481, 1002.7443002034435, 1011.5953618552638, 1014.8731408573929, 4236.343366778155, 4237.8304867804945, 4270.935935960963, 4275.593818587229, 4276.049525827169, 4963.205887058053, 5017.011475876687, 5027.4469808321055, 5034.79860805571, 5035.521625445577, 4211.410402543646, 4224.5353011168745, 4237.5762423758, 4266.274733295348, 4267.743935363888, 2537.771600555922, 2651.912715786518, 2652.7082020977678, 2658.521466136114, 2671.3455203475273, 4664.673472856867, 4693.0992947856175, 4711.434627844681, 4712.481627021025, 4723.291506274087, 4890.970247347382, 5129.8717532062, 5141.408737995103, 5142.30578871855, 5144.897102057937, 4608.308753686973, 4662.790174442091, 4695.882602934886, 4707.59985401231, 4713.29300604912, 4980.078685443204, 4980.775865086008, 5004.3994720633755, 5010.672171874529, 5072.340595722532, 4786.899013226907, 4942.555170034714, 4943.876403089952, 4960.826371076995, 5007.649464537448, 4630.675852690414, 4653.0, 4660.042782031623, 4669.976650116728, 4680.297955306732, 3844.9231015380096, 3846.6922646187677, 3908.687305015535, 3917.196974620139, 3939.448477213135, 4942.579880710036, 5031.295618613324, 5046.772895219798, 5050.898982020265, 5052.570531504716, 4730.881727956691, 4945.826896058621, 5039.067772462106, 5043.319151914445, 5046.924296135488, 5002.124628190125, 5006.4743201963265, 5019.196928491424, 5032.823851165338, 5067.442581316126, 4776.617870570277, 4921.606271498343, 4925.753712314442, 4967.503249675004, 5004.22350065736, 4995.500449954975, 5059.482155353306, 5085.0, 5085.64400491962, 5090.796368145381, 4937.432195297587, 4937.802487900443, 4944.208926571875, 4948.05986862495, 4980.360408983244], "value": 3847.506985338412, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.5106170372221, 676.3135417551183, 740.2930201657417, 773.1186447449908, 792.120745971971, 12985.285809280485, 13978.650533736649, 14047.64880877976, 14064.085834420768, 14076.788848167285, 13289.136206146593, 13506.0, 13542.526011589596, 13545.525906593246, 13550.796738048937, 651.4950913042406, 663.8738639658474, 774.6978678315461, 811.4684881402691, 825.0841565861889, 791.3866753266236, 807.096052421286, 807.3621838747382, 807.563915485637, 808.1877712898529, 872.520113937335, 872.5811610426999, 872.9388942774009, 873.1137895036511, 873.1617647058831, 688.1707542411432, 707.2361849202848, 867.453504292295, 867.7396780965717, 867.8047439326165, 594.9018411962021, 607.59898467012, 611.8653896142841, 612.1124369663961, 612.5681394616785, 13889.374978126009, 13889.930550347184, 13906.58280251594, 13922.443102275896, 13936.442542298297, 13015.479380824756, 13089.214647121107, 13190.142640728322, 13227.86772132276, 13343.599692009255, 11377.488013039432, 11409.885901140966, 11430.599929002445, 11459.598914037968, 11970.82043769344, 4098.315876255902, 4128.958710412918, 4167.6457501112445, 4178.206140833232, 4178.498580170369, 62913.0, 63566.68216658887, 64145.679271603345, 65207.0, 67139.3286067138, 9865.161461275871, 9905.306628536078, 10313.74215644615, 10351.327163734333, 10519.31624444424, 17408.39070632547, 17482.0, 18221.362252321105, 18770.24919003252, 18999.62000759977, 6356.682165891687, 6381.298057213703, 6383.106365108889, 6455.322191169954, 6538.084668146464, 3445.896623101311, 3583.0, 3584.408572585546, 3585.8386372613295, 3587.537401193865, 2727.031903674167, 2793.1201671473423, 2803.733645303693, 2811.1566530040905, 2825.816321939067, 488.2212870471592, 491.1870853737071, 491.6533843640225, 498.1879536355717, 501.2631431795255, 600.83777380108, 603.3544107804695, 624.256393152094, 629.9716512756937, 638.9712462939179, 7864.4888082274465, 8178.345732341399, 8230.21812927771, 8632.266257368068, 8678.0, 572.4475880775072, 579.0879365000142, 581.0441823200811, 598.4404581716109, 598.8203538938301, 4060.8578699745367, 4181.91636167281, 4213.154871897343, 4250.149970006011, 4313.870583882489, 55935.720321398134, 63947.0, 64308.0, 64678.0, 69101.65449172723, 58319.41680583225, 59031.70484147552, 59547.70226148841, 60002.699986499785, 63355.366446335865, 51563.22655160176, 51759.48240517547, 52043.73978130085, 52062.73968630133, 52921.2061819073, 7877.251661092187, 8206.179382061746, 8223.972003499563, 8359.860354476974, 8393.412461127662, 36509.817450912575, 36730.4490432649, 38222.0, 38273.4258986121, 38701.2259754809, 43474.56525434705, 47008.29487557758, 47056.52943470521, 47811.0, 48832.75583622059, 9553.18797902186, 9687.66093186735, 9697.806043879224, 9744.0, 10163.898361016296, 10817.45912704346, 10925.890741092488, 11046.447677616248, 11103.666889993314, 11203.775924481628, 10034.147097496794, 10295.897041029493, 10560.366378017343, 10889.891101088888, 10955.616553420592, 9203.907960920305, 9231.076892310715, 9315.254779617479, 9376.249900008119, 9436.905630943602, 9835.21318294549, 9853.802923941337, 10104.69685909424, 10111.24165687591, 10239.232057595562, 8034.8393032137865, 8100.351971842357, 8197.426180167331, 8402.579871006303, 8543.658253669784, 7746.651400687096, 8076.0, 10120.898791011996, 10287.664553875955, 10404.791904162026], "value": 6204.253665556628, "unit": "score"}], "name": "dromaeo_css", "value": 6363.75023831628, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 114.0, 115.0, 117.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 130.0, 128.0, 170.0, 122.0, 123.0, 123.0, 124.0, 149.0, 126.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 111.0, 76.0, 78.0, 76.0, 75.0, 107.0, 76.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 112.0, 84.0, 84.0, 84.0, 99.0, 86.0, 85.0, 108.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 150.0, 149.0, 148.0, 150.0, 148.0, 149.0, 149.0, 150.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 67.0, 71.0, 68.0, 67.0, 68.0, 68.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 85.0, 88.0, 84.0, 83.0, 85.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 133.0, 131.0, 140.0, 129.0, 130.0, 139.0, 179.0, 143.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 180.0, 177.0, 178.0, 171.0, 175.0, 175.0, 176.0, 177.0, 178.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 67.0, 68.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1543.2000000000003, "alertThreshold": 2.0}]} 12:29:21 INFO - Return code: 0 12:29:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:21 INFO - # TBPL SUCCESS # 12:29:21 INFO - Running post-action listener: _resource_record_post_action 12:29:21 INFO - [mozharness: 2017-01-11 20:29:21.513011Z] Finished run-tests step (success) 12:29:21 INFO - Running post-run listener: _resource_record_post_run 12:29:21 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 383660032; Read time: 12; Write time: 307888 12:29:21 INFO - TinderboxPrint: CPU usage
13.1% 12:29:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:29:21 INFO - TinderboxPrint: I/O write bytes / time
383,660,032 / 307,888 12:29:21 INFO - TinderboxPrint: CPU idle
8,334.2 (86.9%) 12:29:21 INFO - TinderboxPrint: CPU user
1,215.6 (12.7%) 12:29:21 INFO - TinderboxPrint: Swap in / out
0 / 0 12:29:21 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99508224; Read time: 0; Write time: 75984 12:29:21 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 283553792; Read time: 0; Write time: 231872 12:29:22 INFO - Running post-run listener: _upload_blobber_files 12:29:22 INFO - Blob upload gear active. 12:29:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:22 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 12:29:22 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'] 12:29:22 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 12:29:22 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:26 INFO - (blobuploader) - INFO - Done attempting. 12:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:26 INFO - (blobuploader) - INFO - Done attempting. 12:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:29:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:27 INFO - (blobuploader) - INFO - Done attempting. 12:29:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:29:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:29:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:28 INFO - (blobuploader) - INFO - Done attempting. 12:29:28 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:28 INFO - Return code: 0 12:29:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cd99bc06380e70e1eb0175128fba53d462211bcfd7c30607d506aad9773c33ef13ed084923831a1aafbd280ac8af57f42c9d3e0ba222d5f1ce4cd226d15497fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/136432c387370c61ce07527670e22488268e1772219e99b385662accca313301b7eae4b5c08aee3df8fe4cf438ab345b67d8d35023fa2f2fe73b899c2874b8ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29cde031be1ea59ca3d76f09aa6323751b43712cf062d637a6999401b6dc9d0663c3489d6a40a32bbd3e791460168251c6f6b340fcd59abfbceeec889ccd6bdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/46643981f66770ad48e842049a6785898877634f48c42ddd0911337ec175ef362a72f4b0d291fc355537dc3dbc94de55d2f06500fbb070030eb70dfcbe4b4cb7"} 12:29:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:28 INFO - Contents: 12:29:28 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cd99bc06380e70e1eb0175128fba53d462211bcfd7c30607d506aad9773c33ef13ed084923831a1aafbd280ac8af57f42c9d3e0ba222d5f1ce4cd226d15497fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/136432c387370c61ce07527670e22488268e1772219e99b385662accca313301b7eae4b5c08aee3df8fe4cf438ab345b67d8d35023fa2f2fe73b899c2874b8ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29cde031be1ea59ca3d76f09aa6323751b43712cf062d637a6999401b6dc9d0663c3489d6a40a32bbd3e791460168251c6f6b340fcd59abfbceeec889ccd6bdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/46643981f66770ad48e842049a6785898877634f48c42ddd0911337ec175ef362a72f4b0d291fc355537dc3dbc94de55d2f06500fbb070030eb70dfcbe4b4cb7"} 12:29:28 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:28 INFO - Copying logs to upload dir... 12:29:28 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.069683 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-11 12:29:28.429044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:29:28.432973) ========= 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=db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189 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/cd99bc06380e70e1eb0175128fba53d462211bcfd7c30607d506aad9773c33ef13ed084923831a1aafbd280ac8af57f42c9d3e0ba222d5f1ce4cd226d15497fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/136432c387370c61ce07527670e22488268e1772219e99b385662accca313301b7eae4b5c08aee3df8fe4cf438ab345b67d8d35023fa2f2fe73b899c2874b8ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29cde031be1ea59ca3d76f09aa6323751b43712cf062d637a6999401b6dc9d0663c3489d6a40a32bbd3e791460168251c6f6b340fcd59abfbceeec889ccd6bdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/46643981f66770ad48e842049a6785898877634f48c42ddd0911337ec175ef362a72f4b0d291fc355537dc3dbc94de55d2f06500fbb070030eb70dfcbe4b4cb7"} build_url:https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011866 build_url: 'https://queue.taskcluster.net/v1/task/TEyG4_AIQ1uLn9aetAd9rw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cd99bc06380e70e1eb0175128fba53d462211bcfd7c30607d506aad9773c33ef13ed084923831a1aafbd280ac8af57f42c9d3e0ba222d5f1ce4cd226d15497fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/136432c387370c61ce07527670e22488268e1772219e99b385662accca313301b7eae4b5c08aee3df8fe4cf438ab345b67d8d35023fa2f2fe73b899c2874b8ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29cde031be1ea59ca3d76f09aa6323751b43712cf062d637a6999401b6dc9d0663c3489d6a40a32bbd3e791460168251c6f6b340fcd59abfbceeec889ccd6bdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/46643981f66770ad48e842049a6785898877634f48c42ddd0911337ec175ef362a72f4b0d291fc355537dc3dbc94de55d2f06500fbb070030eb70dfcbe4b4cb7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:29:28.458052) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:29:28.458343) ========= 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=db0483c52d987ecb441a263b000001ad-1484165320.72595-853527189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004425 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:29:28.543418) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:29:28.543902) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:29:28.544313) ========= ========= Total master_lag: 0.17 =========